builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0301 starttime: 1495582096.19 results: success (0) revision: 96e18bec9fc8a5ce623c16167c12756bbe190d73 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-23 16:28:16.193794) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-23 16:28:16.194238) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-23 16:28:16.194506) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-23 16:28:16.222498) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 16:28:16.223196) ========= 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.AMrr84TUEh/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.0rg2ptp0BZ/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-23 16:28:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 173M=0s 2017-05-23 16:28:16 (173 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.140935 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 16:28:16.391145) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 16:28:16.391903) ========= 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.AMrr84TUEh/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.0rg2ptp0BZ/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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.033247 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 16:28:16.450917) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-23 16:28:16.451673) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 96e18bec9fc8a5ce623c16167c12756bbe190d73 --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 96e18bec9fc8a5ce623c16167c12756bbe190d73 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AMrr84TUEh/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.0rg2ptp0BZ/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-23 16:28:16,552 truncating revision to first 12 chars 2017-05-23 16:28:16,552 Setting DEBUG logging. 2017-05-23 16:28:16,552 attempt 1/10 2017-05-23 16:28:16,552 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/96e18bec9fc8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-23 16:28:17,518 unpacking tar archive at: mozilla-central-96e18bec9fc8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.252214 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-23 16:28:17.724668) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-23 16:28:17.725048) ========= script_repo_revision: 96e18bec9fc8a5ce623c16167c12756bbe190d73 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-23 16:28:17.725468) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-23 16:28:17.725773) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-23 16:28:17.743744) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-05-23 16:28:17.744069) ========= /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.AMrr84TUEh/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.0rg2ptp0BZ/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 16:28:17 INFO - MultiFileLogger online at 20170523 16:28:17 in /builds/slave/test 16:28:17 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 16:28:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:28:17 INFO - {'append_to_log': False, 16:28:17 INFO - 'base_work_dir': '/builds/slave/test', 16:28:17 INFO - 'blob_upload_branch': 'Firefox', 16:28:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:28:17 INFO - 'branch': 'Firefox', 16:28:17 INFO - 'buildbot_json_path': 'buildprops.json', 16:28:17 INFO - 'config_files': ('talos/mac_config.py',), 16:28:17 INFO - 'default_actions': ('clobber', 16:28:17 INFO - 'read-buildbot-config', 16:28:17 INFO - 'download-and-extract', 16:28:17 INFO - 'populate-webroot', 16:28:17 INFO - 'create-virtualenv', 16:28:17 INFO - 'install', 16:28:17 INFO - 'run-tests'), 16:28:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:28:17 INFO - 'download_minidump_stackwalk': True, 16:28:17 INFO - 'download_symbols': 'ondemand', 16:28:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:28:17 INFO - 'tooltool.py': '/tools/tooltool.py', 16:28:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:28:17 INFO - '/tools/misc-python/virtualenv.py')}, 16:28:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:28:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:28:17 INFO - 'gecko_profile': False, 16:28:17 INFO - 'gecko_profile_interval': 0, 16:28:17 INFO - 'installer_path': 'installer.exe', 16:28:17 INFO - 'log_level': 'info', 16:28:17 INFO - 'log_name': 'talos', 16:28:17 INFO - 'log_to_console': True, 16:28:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:28:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:28:17 INFO - 'opt_config_files': (), 16:28:17 INFO - 'pip_index': False, 16:28:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:28:17 INFO - 'cmd': ('bash', 16:28:17 INFO - '-c', 16:28:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:28:17 INFO - 'enabled': True, 16:28:17 INFO - 'halt_on_failure': False, 16:28:17 INFO - 'name': 'check_screen_resolution'},), 16:28:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:28:17 INFO - 'cmd': ('bash', 16:28:17 INFO - '-c', 16:28:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:28:17 INFO - 'enabled': True, 16:28:17 INFO - 'halt_on_failure': False, 16:28:17 INFO - 'name': 'check_screen_resolution'},), 16:28:17 INFO - 'run_cmd_checks_enabled': True, 16:28:17 INFO - 'suite': 'dromaeojs-e10s', 16:28:17 INFO - 'system_bits': '32', 16:28:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:28:17 INFO - 'title': 't-yosemite-r7-0301', 16:28:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:28:17 INFO - 'use_talos_json': True, 16:28:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:28:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:28:17 INFO - 'work_dir': 'build'} 16:28:17 INFO - [mozharness: 2017-05-23 23:28:17.942814Z] Running clobber step. 16:28:17 INFO - Running pre-action listener: _resource_record_pre_action 16:28:17 INFO - Running main action method: clobber 16:28:17 INFO - rmtree: /builds/slave/test/build 16:28:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:28:20 INFO - Running post-action listener: _resource_record_post_action 16:28:20 INFO - [mozharness: 2017-05-23 23:28:20.487220Z] Finished clobber step (success) 16:28:20 INFO - [mozharness: 2017-05-23 23:28:20.487414Z] Running read-buildbot-config step. 16:28:20 INFO - Running pre-action listener: _resource_record_pre_action 16:28:20 INFO - Running main action method: read_buildbot_config 16:28:20 INFO - Using buildbot properties: 16:28:20 INFO - { 16:28:20 INFO - "project": "", 16:28:20 INFO - "product": "firefox", 16:28:20 INFO - "who": "ryanvm@gmail.com", 16:28:20 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 16:28:20 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 16:28:20 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 16:28:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 16:28:20 INFO - "stage_platform": "macosx64", 16:28:20 INFO - "basedir": "/builds/slave/test", 16:28:20 INFO - "buildnumber": 162, 16:28:20 INFO - "platform": "yosemite_r7", 16:28:20 INFO - "parent_task_id": "STUL3AeuQ4GIFvud50BZtQ", 16:28:20 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 16:28:20 INFO - "slavebuilddir": "test", 16:28:20 INFO - "taskId": "SaWLVO7kRjCVBVvgLut9JA", 16:28:20 INFO - "branch": "mozilla-central", 16:28:20 INFO - "script_repo_revision": "production", 16:28:20 INFO - "revision": "96e18bec9fc8a5ce623c16167c12756bbe190d73", 16:28:20 INFO - "slavename": "t-yosemite-r7-0301", 16:28:20 INFO - "repo_path": "mozilla-central" 16:28:20 INFO - } 16:28:20 INFO - Finding installer, test and symbols from parent task. 16:28:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SaWLVO7kRjCVBVvgLut9JA'}, attempt #1 16:28:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SaWLVO7kRjCVBVvgLut9JA'}, attempt #1 16:28:21 INFO - Task dependencies: MMuOxWI4SLyDdu133Cx-tQ STUL3AeuQ4GIFvud50BZtQ 16:28:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/STUL3AeuQ4GIFvud50BZtQ'}, attempt #1 16:28:22 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/STUL3AeuQ4GIFvud50BZtQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:28:22 INFO - Set test_url: https://queue.taskcluster.net/v1/task/STUL3AeuQ4GIFvud50BZtQ/artifacts/public/build/test_packages.json 16:28:22 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/STUL3AeuQ4GIFvud50BZtQ/artifacts/public/build/target.crashreporter-symbols.zip 16:28:22 INFO - Running post-action listener: _resource_record_post_action 16:28:22 INFO - [mozharness: 2017-05-23 23:28:22.276065Z] Finished read-buildbot-config step (success) 16:28:22 INFO - [mozharness: 2017-05-23 23:28:22.276184Z] Running download-and-extract step. 16:28:22 INFO - Running pre-action listener: _resource_record_pre_action 16:28:22 INFO - Running main action method: download_and_extract 16:28:22 INFO - mkdir: /builds/slave/test/build/tests 16:28: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')]} 16:28:22 INFO - trying https://queue.taskcluster.net/v1/task/STUL3AeuQ4GIFvud50BZtQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 16:28:22 INFO - Downloading https://queue.taskcluster.net/v1/task/STUL3AeuQ4GIFvud50BZtQ/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 16:28:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/STUL3AeuQ4GIFvud50BZtQ/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 16:28:23 INFO - Downloaded 1325 bytes. 16:28:23 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:28:23 INFO - Using the following test package requirements: 16:28:23 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:28:23 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 16:28:23 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:28:23 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:28:23 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 16:28:23 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:28:23 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 16:28:23 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:28:23 INFO - u'jsshell-mac.zip'], 16:28:23 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:28:23 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 16:28:23 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:28:23 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:28:23 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 16:28:23 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:28:23 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 16:28:23 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:28:23 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 16:28:23 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:28:23 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 16:28:23 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:28:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/STUL3AeuQ4GIFvud50BZtQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:28:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/STUL3AeuQ4GIFvud50BZtQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:28:23 INFO - Fetch https://queue.taskcluster.net/v1/task/STUL3AeuQ4GIFvud50BZtQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:28:24 INFO - Content-Length response header: 57628365 16:28:24 INFO - Bytes received: 57628365 16:28:42 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 16:28:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/STUL3AeuQ4GIFvud50BZtQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:28:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/STUL3AeuQ4GIFvud50BZtQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:28:42 INFO - Fetch https://queue.taskcluster.net/v1/task/STUL3AeuQ4GIFvud50BZtQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:28:44 INFO - Content-Length response header: 57628365 16:28:44 INFO - Bytes received: 57628365 16:29:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/STUL3AeuQ4GIFvud50BZtQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 16:29:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/STUL3AeuQ4GIFvud50BZtQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:29:01 INFO - Fetch https://queue.taskcluster.net/v1/task/STUL3AeuQ4GIFvud50BZtQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 16:29:02 INFO - Content-Length response header: 14411859 16:29:02 INFO - Bytes received: 14411859 16:29:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:03 INFO - trying https://queue.taskcluster.net/v1/task/STUL3AeuQ4GIFvud50BZtQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:29:03 INFO - Downloading https://queue.taskcluster.net/v1/task/STUL3AeuQ4GIFvud50BZtQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 16:29:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/STUL3AeuQ4GIFvud50BZtQ/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 16:29:05 INFO - Downloaded 68008420 bytes. 16:29:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/STUL3AeuQ4GIFvud50BZtQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:29:05 INFO - mkdir: /builds/slave/test/properties 16:29:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:29:05 INFO - Writing to file /builds/slave/test/properties/build_url 16:29:05 INFO - Contents: 16:29:05 INFO - build_url:https://queue.taskcluster.net/v1/task/STUL3AeuQ4GIFvud50BZtQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:29:05 INFO - Running post-action listener: _resource_record_post_action 16:29:05 INFO - Running post-action listener: set_extra_try_arguments 16:29:05 INFO - [mozharness: 2017-05-23 23:29:05.364651Z] Finished download-and-extract step (success) 16:29:05 INFO - [mozharness: 2017-05-23 23:29:05.364788Z] Running populate-webroot step. 16:29:05 INFO - Running pre-action listener: _resource_record_pre_action 16:29:05 INFO - Running main action method: populate_webroot 16:29:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:29:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:29:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:29:05 INFO - '--tppagecycles', 16:29:05 INFO - '7'], 16:29:05 INFO - 'tests': ['tsvgm']}}, 16:29:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:29:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:29:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:29:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:29:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:29:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:29:05 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:29:05 INFO - 'talos_options': ['--disable-e10s'], 16:29:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:29:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:29:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:29:05 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:29:05 INFO - 'talos_options': ['--disable-e10s'], 16:29:05 INFO - 'tests': ['damp', 'tps']}, 16:29:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:29:05 INFO - 'tests': ['damp', 'tps']}, 16:29:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:29:05 INFO - 'tests': ['dromaeo_dom']}, 16:29:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:29:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:29:05 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 16:29:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:29:05 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:29:05 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:29:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:29:05 INFO - 'tests': ['a11yr', 16:29:05 INFO - 'ts_paint', 16:29:05 INFO - 'tpaint', 16:29:05 INFO - 'sessionrestore', 16:29:05 INFO - 'sessionrestore_no_auto_restore', 16:29:05 INFO - 'tabpaint']}, 16:29:05 INFO - 'other-e10s': {'tests': ['a11yr', 16:29:05 INFO - 'ts_paint', 16:29:05 INFO - 'tpaint', 16:29:05 INFO - 'sessionrestore', 16:29:05 INFO - 'sessionrestore_no_auto_restore', 16:29:05 INFO - 'tabpaint']}, 16:29:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:29:05 INFO - 'ts_paint', 16:29:05 INFO - 'tpaint', 16:29:05 INFO - 'sessionrestore', 16:29:05 INFO - 'sessionrestore_no_auto_restore', 16:29:05 INFO - 'tabpaint']}, 16:29:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:29:05 INFO - 'ts_paint', 16:29:05 INFO - 'tpaint', 16:29:05 INFO - 'sessionrestore', 16:29:05 INFO - 'sessionrestore_no_auto_restore', 16:29:05 INFO - 'tabpaint']}, 16:29:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:29:05 INFO - 'tests': ['a11yr', 16:29:05 INFO - 'ts_paint', 16:29:05 INFO - 'tpaint', 16:29:05 INFO - 'sessionrestore', 16:29:05 INFO - 'sessionrestore_no_auto_restore', 16:29:05 INFO - 'tabpaint']}, 16:29:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:29:05 INFO - 'tests': ['a11yr', 16:29:05 INFO - 'ts_paint', 16:29:05 INFO - 'tpaint', 16:29:05 INFO - 'sessionrestore', 16:29:05 INFO - 'sessionrestore_no_auto_restore', 16:29:05 INFO - 'tabpaint']}, 16:29:05 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 16:29:05 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 16:29:05 INFO - 'bloom_basic_ref']}, 16:29:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:29:05 INFO - 'tests': ['tsvgx', 16:29:05 INFO - 'tsvgr_opacity', 16:29:05 INFO - 'tart', 16:29:05 INFO - 'tscrollx', 16:29:05 INFO - 'cart', 16:29:05 INFO - 'tsvg_static']}, 16:29:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:29:05 INFO - 'tsvgr_opacity', 16:29:05 INFO - 'tart', 16:29:05 INFO - 'tscrollx', 16:29:05 INFO - 'cart', 16:29:05 INFO - 'tsvg_static']}, 16:29:05 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:29:05 INFO - 'talos_options': ['--disable-e10s'], 16:29:05 INFO - 'tests': ['tp5o']}, 16:29:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:29:05 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:29:05 INFO - 'talos_options': ['--disable-e10s', 16:29:05 INFO - '--xperf_path', 16:29:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:29:05 INFO - 'tests': ['tp5n']}, 16:29:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:29:05 INFO - 'talos_options': ['--xperf_path', 16:29:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:29:05 INFO - 'tests': ['tp5n']}}, 16:29:05 INFO - 'talos.zip': {'path': '', 16:29:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:29:05 INFO - Running post-action listener: _resource_record_post_action 16:29:05 INFO - [mozharness: 2017-05-23 23:29:05.386656Z] Finished populate-webroot step (success) 16:29:05 INFO - [mozharness: 2017-05-23 23:29:05.386780Z] Running create-virtualenv step. 16:29:05 INFO - Running pre-action listener: _resource_record_pre_action 16:29:05 INFO - Running main action method: create_virtualenv 16:29:05 INFO - Creating virtualenv /builds/slave/test/build/venv 16:29:05 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 16:29:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:29:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:29:05 INFO - Using real prefix '/tools/python27' 16:29:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:29:06 INFO - Installing distribute.............................................................................................................................................................................................done. 16:29:09 INFO - Installing pip.................done. 16:29:09 INFO - Return code: 0 16:29:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:29:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:29:09 INFO - Reading from file tmpfile_stdout 16:29:09 INFO - Output received: 16:29:09 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) 16:29:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10882cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108830768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2d14a6400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10853cd30>, '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 0x1084f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108531be8>, '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.AMrr84TUEh/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.0rg2ptp0BZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 16:29:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:29:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:29:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AMrr84TUEh/Render', 16:29:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:09 INFO - 'HOME': '/Users/cltbld', 16:29:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:09 INFO - 'LOGNAME': 'cltbld', 16:29:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:09 INFO - 'MOZ_NO_REMOTE': '1', 16:29:09 INFO - 'NO_EM_RESTART': '1', 16:29:09 INFO - 'PAGER': '/bin/cat', 16:29:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:09 INFO - 'PWD': '/builds/slave/test', 16:29:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:09 INFO - 'SHELL': '/bin/bash', 16:29:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0rg2ptp0BZ/Listeners', 16:29:09 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 16:29:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:09 INFO - 'USER': 'cltbld', 16:29:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:09 INFO - 'XPC_FLAGS': '0x0', 16:29:09 INFO - 'XPC_SERVICE_NAME': '0', 16:29:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:09 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:29:09 INFO - Cleaning up... 16:29:09 INFO - Return code: 0 16:29:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10882cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108830768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2d14a6400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10853cd30>, '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 0x1084f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108531be8>, '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.AMrr84TUEh/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.0rg2ptp0BZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 16:29:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:29:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:29:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AMrr84TUEh/Render', 16:29:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:09 INFO - 'HOME': '/Users/cltbld', 16:29:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:09 INFO - 'LOGNAME': 'cltbld', 16:29:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:09 INFO - 'MOZ_NO_REMOTE': '1', 16:29:09 INFO - 'NO_EM_RESTART': '1', 16:29:09 INFO - 'PAGER': '/bin/cat', 16:29:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:09 INFO - 'PWD': '/builds/slave/test', 16:29:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:09 INFO - 'SHELL': '/bin/bash', 16:29:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0rg2ptp0BZ/Listeners', 16:29:09 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 16:29:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:09 INFO - 'USER': 'cltbld', 16:29:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:09 INFO - 'XPC_FLAGS': '0x0', 16:29:09 INFO - 'XPC_SERVICE_NAME': '0', 16:29:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:10 INFO - Downloading/unpacking psutil>=3.1.1 16:29: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 16:29: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 16:29: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 16:29: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 16:29:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:29:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:29:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:29:11 INFO - Installing collected packages: psutil 16:29:11 INFO - Running setup.py install for psutil 16:29:12 INFO - building 'psutil._psutil_osx' extension 16:29:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 16:29:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 16:29:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 16:29:12 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 16:29:12 INFO - building 'psutil._psutil_posix' extension 16:29:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 16:29:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:29:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:29:12 INFO - ^ 16:29:12 INFO - 1 warning generated. 16:29:12 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 16:29:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:29:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:29:13 INFO - Successfully installed psutil 16:29:13 INFO - Cleaning up... 16:29:13 INFO - Return code: 0 16:29:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:29:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:13 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 0x10882cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108830768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2d14a6400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10853cd30>, '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 0x1084f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108531be8>, '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.AMrr84TUEh/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.0rg2ptp0BZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 16:29:13 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 16:29:13 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 16:29:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AMrr84TUEh/Render', 16:29:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:13 INFO - 'HOME': '/Users/cltbld', 16:29:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:13 INFO - 'LOGNAME': 'cltbld', 16:29:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:13 INFO - 'MOZ_NO_REMOTE': '1', 16:29:13 INFO - 'NO_EM_RESTART': '1', 16:29:13 INFO - 'PAGER': '/bin/cat', 16:29:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:13 INFO - 'PWD': '/builds/slave/test', 16:29:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:13 INFO - 'SHELL': '/bin/bash', 16:29:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0rg2ptp0BZ/Listeners', 16:29:13 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 16:29:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:13 INFO - 'USER': 'cltbld', 16:29:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:13 INFO - 'XPC_FLAGS': '0x0', 16:29:13 INFO - 'XPC_SERVICE_NAME': '0', 16:29:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:29:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:29:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:29:16 INFO - Installing collected packages: mozsystemmonitor 16:29:16 INFO - Running setup.py install for mozsystemmonitor 16:29:16 INFO - Successfully installed mozsystemmonitor 16:29:16 INFO - Cleaning up... 16:29:16 INFO - Return code: 0 16:29:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:29:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10882cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108830768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2d14a6400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10853cd30>, '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 0x1084f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108531be8>, '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.AMrr84TUEh/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.0rg2ptp0BZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 16:29:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:29:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:29:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AMrr84TUEh/Render', 16:29:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:16 INFO - 'HOME': '/Users/cltbld', 16:29:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:16 INFO - 'LOGNAME': 'cltbld', 16:29:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:16 INFO - 'MOZ_NO_REMOTE': '1', 16:29:16 INFO - 'NO_EM_RESTART': '1', 16:29:16 INFO - 'PAGER': '/bin/cat', 16:29:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:16 INFO - 'PWD': '/builds/slave/test', 16:29:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:16 INFO - 'SHELL': '/bin/bash', 16:29:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0rg2ptp0BZ/Listeners', 16:29:16 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 16:29:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:16 INFO - 'USER': 'cltbld', 16:29:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:16 INFO - 'XPC_FLAGS': '0x0', 16:29:16 INFO - 'XPC_SERVICE_NAME': '0', 16:29:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:16 INFO - Downloading/unpacking jsonschema==2.5.1 16:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:29:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:18 INFO - Downloading functools32-3.2.3-2.tar.gz 16:29:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:29:18 INFO - warning: no files found matching '*.txt' 16:29:18 INFO - no previously-included directories found matching 'build' 16:29:18 INFO - no previously-included directories found matching 'dist' 16:29:18 INFO - no previously-included directories found matching '.git*' 16:29:18 INFO - Installing collected packages: jsonschema, functools32 16:29:18 INFO - Running setup.py install for functools32 16:29:18 INFO - warning: no files found matching '*.txt' 16:29:18 INFO - no previously-included directories found matching 'build' 16:29:18 INFO - no previously-included directories found matching 'dist' 16:29:18 INFO - no previously-included directories found matching '.git*' 16:29:18 INFO - Successfully installed jsonschema functools32 16:29:18 INFO - Cleaning up... 16:29:18 INFO - Return code: 0 16:29:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:29: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')]} 16:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29: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')]} 16:29:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:18 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 0x10882cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108830768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2d14a6400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10853cd30>, '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 0x1084f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108531be8>, '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.AMrr84TUEh/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.0rg2ptp0BZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 16:29:18 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 16:29:18 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 16:29:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AMrr84TUEh/Render', 16:29:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:18 INFO - 'HOME': '/Users/cltbld', 16:29:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:18 INFO - 'LOGNAME': 'cltbld', 16:29:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:18 INFO - 'MOZ_NO_REMOTE': '1', 16:29:18 INFO - 'NO_EM_RESTART': '1', 16:29:18 INFO - 'PAGER': '/bin/cat', 16:29:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:18 INFO - 'PWD': '/builds/slave/test', 16:29:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:18 INFO - 'SHELL': '/bin/bash', 16:29:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0rg2ptp0BZ/Listeners', 16:29:18 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 16:29:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:18 INFO - 'USER': 'cltbld', 16:29:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:18 INFO - 'XPC_FLAGS': '0x0', 16:29:18 INFO - 'XPC_SERVICE_NAME': '0', 16:29:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:29:18 INFO - Cleaning up... 16:29:18 INFO - Return code: 0 16:29:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:29: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')]} 16:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29: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')]} 16:29:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:18 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 0x10882cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108830768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2d14a6400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10853cd30>, '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 0x1084f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108531be8>, '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.AMrr84TUEh/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.0rg2ptp0BZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 16:29:18 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 16:29:18 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 16:29:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AMrr84TUEh/Render', 16:29:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:18 INFO - 'HOME': '/Users/cltbld', 16:29:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:18 INFO - 'LOGNAME': 'cltbld', 16:29:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:18 INFO - 'MOZ_NO_REMOTE': '1', 16:29:18 INFO - 'NO_EM_RESTART': '1', 16:29:18 INFO - 'PAGER': '/bin/cat', 16:29:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:18 INFO - 'PWD': '/builds/slave/test', 16:29:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:18 INFO - 'SHELL': '/bin/bash', 16:29:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0rg2ptp0BZ/Listeners', 16:29:18 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 16:29:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:18 INFO - 'USER': 'cltbld', 16:29:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:18 INFO - 'XPC_FLAGS': '0x0', 16:29:18 INFO - 'XPC_SERVICE_NAME': '0', 16:29:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:19 INFO - Downloading/unpacking blobuploader==1.2.4 16:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:20 INFO - Downloading blobuploader-1.2.4.tar.gz 16:29:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:29:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:29: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 16:29: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 16:29: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 16:29: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 16:29:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:29:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:21 INFO - Downloading docopt-0.6.1.tar.gz 16:29:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:29:21 INFO - Installing collected packages: blobuploader, requests, docopt 16:29:21 INFO - Running setup.py install for blobuploader 16:29:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:29:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:29:21 INFO - Running setup.py install for requests 16:29:22 INFO - Running setup.py install for docopt 16:29:22 INFO - Successfully installed blobuploader requests docopt 16:29:22 INFO - Cleaning up... 16:29:22 INFO - Return code: 0 16:29:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:29: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')]} 16:29:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29: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')]} 16:29:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:22 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 0x10882cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108830768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2d14a6400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10853cd30>, '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 0x1084f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108531be8>, '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.AMrr84TUEh/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.0rg2ptp0BZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 16:29:22 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 16:29:22 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 16:29:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AMrr84TUEh/Render', 16:29:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:22 INFO - 'HOME': '/Users/cltbld', 16:29:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:22 INFO - 'LOGNAME': 'cltbld', 16:29:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:22 INFO - 'MOZ_NO_REMOTE': '1', 16:29:22 INFO - 'NO_EM_RESTART': '1', 16:29:22 INFO - 'PAGER': '/bin/cat', 16:29:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:22 INFO - 'PWD': '/builds/slave/test', 16:29:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:22 INFO - 'SHELL': '/bin/bash', 16:29:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0rg2ptp0BZ/Listeners', 16:29:22 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 16:29:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:22 INFO - 'USER': 'cltbld', 16:29:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:22 INFO - 'XPC_FLAGS': '0x0', 16:29:22 INFO - 'XPC_SERVICE_NAME': '0', 16:29:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:29:22 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-Za7KpT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:29:22 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-k0irIo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:29:22 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-J0yAfV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:29:22 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-FkQFRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:29:22 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-80hEOy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:29:22 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-T5xRO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:29:23 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-NCByZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:29:23 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-fWtzxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:29:23 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-WdAE17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:29:23 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-i5mrZy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:29:23 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-yHOhF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:29:23 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-jQyoVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:29:23 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-LxCjuj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:29:23 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-4nmxte-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:23 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-8CXCvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:29:24 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-pwC2fx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:29:24 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-7SW7IF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:29:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:29:24 INFO - Running setup.py install for manifestparser 16:29:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:29:24 INFO - Running setup.py install for mozcrash 16:29:24 INFO - Running setup.py install for mozdebug 16:29:24 INFO - Running setup.py install for mozdevice 16:29:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:29:24 INFO - Running setup.py install for mozfile 16:29:24 INFO - Running setup.py install for mozhttpd 16:29:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:29:25 INFO - Running setup.py install for mozinfo 16:29:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:29:25 INFO - Running setup.py install for mozInstall 16:29:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:29:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:29:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:29:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:29:25 INFO - Running setup.py install for mozleak 16:29:25 INFO - Running setup.py install for mozlog 16:29:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:29:25 INFO - Running setup.py install for moznetwork 16:29:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:29:25 INFO - Running setup.py install for mozprocess 16:29:25 INFO - Running setup.py install for mozprofile 16:29:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:29:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:29:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:29:26 INFO - Running setup.py install for mozrunner 16:29:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:29:26 INFO - Running setup.py install for mozscreenshot 16:29:26 INFO - Running setup.py install for moztest 16:29:26 INFO - Running setup.py install for mozversion 16:29:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:29:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:29:26 INFO - Cleaning up... 16:29:26 INFO - Return code: 0 16:29:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:29:26 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')]} 16:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:26 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')]} 16:29:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:26 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 0x10882cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108830768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2d14a6400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10853cd30>, '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 0x1084f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108531be8>, '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.AMrr84TUEh/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.0rg2ptp0BZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 16:29:26 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 16:29:26 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 16:29:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AMrr84TUEh/Render', 16:29:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:26 INFO - 'HOME': '/Users/cltbld', 16:29:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:26 INFO - 'LOGNAME': 'cltbld', 16:29:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:26 INFO - 'MOZ_NO_REMOTE': '1', 16:29:26 INFO - 'NO_EM_RESTART': '1', 16:29:26 INFO - 'PAGER': '/bin/cat', 16:29:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:26 INFO - 'PWD': '/builds/slave/test', 16:29:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:26 INFO - 'SHELL': '/bin/bash', 16:29:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0rg2ptp0BZ/Listeners', 16:29:26 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 16:29:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:26 INFO - 'USER': 'cltbld', 16:29:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:26 INFO - 'XPC_FLAGS': '0x0', 16:29:26 INFO - 'XPC_SERVICE_NAME': '0', 16:29:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:29:27 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-Wgwans-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:29:27 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)) 16:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:29:27 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-82bEbh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:29:27 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)) 16:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:29:27 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-KX8Mga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:29:27 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)) 16:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:29:27 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-IPj6yQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:29:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 16:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:29:27 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-CELJ4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:29:27 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)) 16:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:29:27 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-iZDZuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:29:27 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)) 16:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:29:27 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-eqPPxx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:29:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:29:27 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-C6WT6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:29:27 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)) 16:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:29:27 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-phLrMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:29:27 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)) 16:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:29:28 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-w8stJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:29:28 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)) 16:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:29:28 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-ulJiak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:29:28 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)) 16:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:29:28 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-jvwgtr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:29:28 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)) 16:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:29:28 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-N1xno7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:29:28 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)) 16:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:29:28 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-VZQwwT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:29:28 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)) 16:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:28 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-aHAbKR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:28 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)) 16:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:29:28 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-QTNsD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:29:28 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)) 16:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:29:28 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-I6gUKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:29:28 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)) 16:29:28 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)) 16:29:28 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)) 16:29:28 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)) 16:29:28 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:29:28 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:29:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:29:28 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 16:29:28 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 16:29:28 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 16:29:28 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 16:29:30 INFO - Downloading blessings-1.6.tar.gz 16:29:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:29:30 INFO - Installing collected packages: blessings 16:29:30 INFO - Running setup.py install for blessings 16:29:30 INFO - Successfully installed blessings 16:29:30 INFO - Cleaning up... 16:29:30 INFO - Return code: 0 16:29:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:29:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:29:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:29:30 INFO - Reading from file tmpfile_stdout 16:29:30 INFO - Current package versions: 16:29:30 INFO - blessings == 1.6 16:29:30 INFO - blobuploader == 1.2.4 16:29:30 INFO - docopt == 0.6.1 16:29:30 INFO - functools32 == 3.2.3-2 16:29:30 INFO - jsonschema == 2.5.1 16:29:30 INFO - manifestparser == 1.1 16:29:30 INFO - mozInstall == 1.12 16:29:30 INFO - mozcrash == 1.0 16:29:30 INFO - mozdebug == 0.1 16:29:30 INFO - mozdevice == 0.50 16:29:30 INFO - mozfile == 1.2 16:29:30 INFO - mozhttpd == 0.7 16:29:30 INFO - mozinfo == 0.9 16:29:30 INFO - mozleak == 0.1 16:29:30 INFO - mozlog == 3.4 16:29:30 INFO - moznetwork == 0.27 16:29:30 INFO - mozprocess == 0.25 16:29:30 INFO - mozprofile == 0.28 16:29:30 INFO - mozrunner == 6.13 16:29:30 INFO - mozscreenshot == 0.1 16:29:30 INFO - mozsystemmonitor == 0.3 16:29:30 INFO - moztest == 0.8 16:29:30 INFO - mozversion == 1.4 16:29:30 INFO - psutil == 3.1.1 16:29:30 INFO - requests == 1.2.3 16:29:30 INFO - wsgiref == 0.1.2 16:29:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:29:30 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')]} 16:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:30 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')]} 16:29:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:30 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 0x10882cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108830768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2d14a6400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10853cd30>, '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 0x1084f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108531be8>, '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.AMrr84TUEh/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.0rg2ptp0BZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 16:29:30 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 16:29:30 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 16:29:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AMrr84TUEh/Render', 16:29:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:30 INFO - 'HOME': '/Users/cltbld', 16:29:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:30 INFO - 'LOGNAME': 'cltbld', 16:29:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:30 INFO - 'MOZ_NO_REMOTE': '1', 16:29:30 INFO - 'NO_EM_RESTART': '1', 16:29:30 INFO - 'PAGER': '/bin/cat', 16:29:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:30 INFO - 'PWD': '/builds/slave/test', 16:29:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:30 INFO - 'SHELL': '/bin/bash', 16:29:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0rg2ptp0BZ/Listeners', 16:29:30 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 16:29:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:30 INFO - 'USER': 'cltbld', 16:29:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:30 INFO - 'XPC_FLAGS': '0x0', 16:29:30 INFO - 'XPC_SERVICE_NAME': '0', 16:29:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:31 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)) 16:29:31 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)) 16:29:31 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)) 16:29:31 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)) 16:29:31 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)) 16:29:31 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)) 16:29:31 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)) 16:29:31 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)) 16:29:31 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)) 16:29:31 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:29:31 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 16:29:31 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 16:29:31 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 16:29:31 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 16:29:32 INFO - Downloading marionette_driver-1.4.0.tar.gz 16:29:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 16:29:32 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)) 16:29:32 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)) 16:29:32 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)) 16:29:32 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)) 16:29:32 INFO - Installing collected packages: marionette-driver 16:29:32 INFO - Running setup.py install for marionette-driver 16:29:32 INFO - Successfully installed marionette-driver 16:29:32 INFO - Cleaning up... 16:29:32 INFO - Return code: 0 16:29:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:29:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10882cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108830768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2d14a6400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10853cd30>, '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 0x1084f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108531be8>, '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.AMrr84TUEh/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.0rg2ptp0BZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 16:29:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:29:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:29:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AMrr84TUEh/Render', 16:29:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:32 INFO - 'HOME': '/Users/cltbld', 16:29:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:32 INFO - 'LOGNAME': 'cltbld', 16:29:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:32 INFO - 'MOZ_NO_REMOTE': '1', 16:29:32 INFO - 'NO_EM_RESTART': '1', 16:29:32 INFO - 'PAGER': '/bin/cat', 16:29:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:32 INFO - 'PWD': '/builds/slave/test', 16:29:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:32 INFO - 'SHELL': '/bin/bash', 16:29:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0rg2ptp0BZ/Listeners', 16:29:32 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 16:29:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:32 INFO - 'USER': 'cltbld', 16:29:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:32 INFO - 'XPC_FLAGS': '0x0', 16:29:32 INFO - 'XPC_SERVICE_NAME': '0', 16:29:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:29:33 INFO - Cleaning up... 16:29:33 INFO - Return code: 0 16:29:33 INFO - Running post-action listener: _resource_record_post_action 16:29:33 INFO - Running post-action listener: _start_resource_monitoring 16:29:33 INFO - Starting resource monitoring. 16:29:33 INFO - [mozharness: 2017-05-23 23:29:33.100906Z] Finished create-virtualenv step (success) 16:29:33 INFO - [mozharness: 2017-05-23 23:29:33.101859Z] Running install step. 16:29:33 INFO - Running pre-action listener: _resource_record_pre_action 16:29:33 INFO - Running main action method: install 16:29:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:29:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:29:33 INFO - Reading from file tmpfile_stdout 16:29:33 INFO - Detecting whether we're running mozinstall >=1.0... 16:29:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:29:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:29:33 INFO - Reading from file tmpfile_stdout 16:29:33 INFO - Output received: 16:29:33 INFO - Usage: mozinstall [options] installer 16:29:33 INFO - Options: 16:29:33 INFO - -h, --help show this help message and exit 16:29:33 INFO - -d DEST, --destination=DEST 16:29:33 INFO - Directory to install application into. [default: 16:29:33 INFO - "/builds/slave/test"] 16:29:33 INFO - --app=APP Application being installed. [default: firefox] 16:29:33 INFO - mkdir: /builds/slave/test/build/application 16:29:33 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'] 16:29:33 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 16:29:54 INFO - Reading from file tmpfile_stdout 16:29:54 INFO - Output received: 16:29:54 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:29:54 INFO - Running post-action listener: _resource_record_post_action 16:29:54 INFO - [mozharness: 2017-05-23 23:29:54.585213Z] Finished install step (success) 16:29:54 INFO - [mozharness: 2017-05-23 23:29:54.585376Z] Running run-tests step. 16:29:54 INFO - Running pre-action listener: _resource_record_pre_action 16:29:54 INFO - Running main action method: run_tests 16:29:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:29:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:29:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:29:54 INFO - 2017-05-23 16:29:54.599 screenresolution[1627:7345] starting screenresolution argv=screenresolution get 16:29:54 INFO - 2017-05-23 16:29:54.602 screenresolution[1627:7345] Display 0: 1600x1200x32@60 16:29:54 INFO - 2017-05-23 16:29:54.608 screenresolution[1628:7348] starting screenresolution argv=screenresolution list 16:29:54 INFO - Available Modes on Display 0 16:29:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:29:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:29:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:29:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:29:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:29:54 INFO - Intel Iris: 16:29:54 INFO - Chipset Model: Intel Iris 16:29:54 INFO - Type: GPU 16:29:54 INFO - Bus: Built-In 16:29:54 INFO - VRAM (Dynamic, Max): 1536 MB 16:29:54 INFO - Vendor: Intel (0x8086) 16:29:54 INFO - Device ID: 0x0a2e 16:29:54 INFO - Revision ID: 0x0009 16:29:54 INFO - Displays: 16:29:54 INFO - Display: 16:29:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:29:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:29:54 INFO - Display Serial Number: 1600x1200 60 16:29:54 INFO - Main Display: Yes 16:29:54 INFO - Mirror: Off 16:29:54 INFO - Online: Yes 16:29:54 INFO - Rotation: Supported 16:29:54 INFO - Return code: 0 16:29:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:29:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:29:54 INFO - Python 2.7.3 16:29:54 INFO - Return code: 0 16:29:54 INFO - grabbing minidump binary from tooltool 16:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:54 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 0x10853cd30>, '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 0x1084f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108531be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:29:54 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 16:29:54 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 16:29:54 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 16:29:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:29:54 INFO - Return code: 0 16:29:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:29:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:29:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:29:54 INFO - ENV: RUST_BACKTRACE is now 1 16:29:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:29:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:29:54 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/STUL3AeuQ4GIFvud50BZtQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0301', '--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 16:29:54 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/STUL3AeuQ4GIFvud50BZtQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0301 --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 16:29:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AMrr84TUEh/Render', 16:29:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:54 INFO - 'HOME': '/Users/cltbld', 16:29:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:54 INFO - 'LOGNAME': 'cltbld', 16:29:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:29:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:54 INFO - 'MOZ_NO_REMOTE': '1', 16:29:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:29:54 INFO - 'NO_EM_RESTART': '1', 16:29:54 INFO - 'PAGER': '/bin/cat', 16:29:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:54 INFO - 'PWD': '/builds/slave/test', 16:29:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:29:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:54 INFO - 'RUST_BACKTRACE': '1', 16:29:54 INFO - 'SHELL': '/bin/bash', 16:29:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0rg2ptp0BZ/Listeners', 16:29:54 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 16:29:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:54 INFO - 'USER': 'cltbld', 16:29:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:54 INFO - 'XPC_FLAGS': '0x0', 16:29:54 INFO - 'XPC_SERVICE_NAME': '0', 16:29:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:54 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/STUL3AeuQ4GIFvud50BZtQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0301', '--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 16:29:55 INFO - mozversion application_buildid: 20170523151434 16:29:55 INFO - mozversion application_changeset: 96e18bec9fc8a5ce623c16167c12756bbe190d73 16:29:55 INFO - mozversion application_display_name: Nightly 16:29:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:29:55 INFO - mozversion application_name: Firefox 16:29:55 INFO - mozversion application_remotingname: firefox 16:29:55 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:29:55 INFO - mozversion application_vendor: Mozilla 16:29:55 INFO - mozversion application_version: 55.0a1 16:29:55 INFO - mozversion platform_buildid: 20170523151434 16:29:55 INFO - mozversion platform_changeset: 96e18bec9fc8a5ce623c16167c12756bbe190d73 16:29:55 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:29:55 INFO - mozversion platform_version: 55.0a1 16:29:55 INFO - using testdate: 1495582195 16:29:55 INFO - actual date: 1495582195 16:29:55 INFO - starting webserver on 'localhost:49226' 16:29:55 INFO - SUITE-START | Running 2 tests 16:29:55 INFO - TEST-START | dromaeo_css 16:29:55 INFO - operating with platform_type : mac_ 16:29:55 INFO - Initialising browser for dromaeo_css test... 16:29:55 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpb8ir1q/profile 16:29:55 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground) 16:30:28 INFO - TEST-INFO | 1638: killed by SIGTERM 16:30:28 INFO - Browser initialized. 16:30:28 INFO - Running cycle 1/1 for dromaeo_css test... 16:30:28 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpb8ir1q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:30:30 INFO - PID 1649 | 2017-05-23 16:30:30.947 plugin-container[1650:7926] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 16:30:30 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:30:30 INFO - PID 1649 | 2017-05-23 16:30:30.948 plugin-container[1650:7926] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 16:30:30 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:30:30 INFO - PID 1649 | 2017-05-23 16:30:30.948 plugin-container[1650:7926] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:30:31 INFO - PID 1649 | 2017-05-23 16:30:31.413 plugin-container[1651:7971] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 16:30:31 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:30:31 INFO - PID 1649 | 2017-05-23 16:30:31.413 plugin-container[1651:7971] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 16:30:31 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:30:31 INFO - PID 1649 | 2017-05-23 16:30:31.413 plugin-container[1651:7971] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:30:40 INFO - PID 1649 | RSS: Main: 250183680 16:30:40 INFO - PID 1649 | 16:33:43 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 16:33:45 INFO - PID 1649 | RSS: Main: 260067328 16:33:45 INFO - PID 1649 | 16:36:46 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 16:36:47 INFO - PID 1649 | RSS: Main: 273702912 16:36:47 INFO - PID 1649 | 16:39:49 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 16:39:49 INFO - PID 1649 | RSS: Main: 273903616 16:39:49 INFO - PID 1649 | 16:42:51 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 16:42:52 INFO - PID 1649 | RSS: Main: 278867968 16:42:52 INFO - PID 1649 | 16:45:55 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 16:45:55 INFO - PID 1649 | RSS: Main: 278970368 16:45:55 INFO - PID 1649 | 16:48:57 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 16:48:57 INFO - PID 1649 | RSS: Main: 278953984 16:48:57 INFO - PID 1649 | 16:48:57 INFO - PID 1649 | __start_tp_report 16:48:57 INFO - PID 1649 | _x_x_mozilla_page_load 16:48:57 INFO - PID 1649 | _x_x_mozilla_page_load_details 16:48:57 INFO - PID 1649 | |i|pagename|runs| 16:48:57 INFO - PID 1649 | |0;dojo.html;3229.370272796805;3361.1765117546174;3406.5692409188136;3595.2270261893696;3599.15419876329;5100.617453690974;5252.921206181905;5881.617694849842;6138.662373569452;6143.600665956709;6154.569180157391;6167.444929956303;6190.226221722285;6243.594166379182;6261.996161228409;5298.841034768963;5436.374816896068;5461.2354270402175;5477.808276710316;5547.44525547446;5553.194786755918;5566.721663916808;5568;5573.5819813513945;5579.553635709154;5389.649672771257;5463.2078348639625;5466.34403871534;5514;5539.695316757597;5505.642133261365;5514.6691198527815;5548.278723765885;5557.916631250535;5570.637908535973;5441.29263195786;5452.345718513804;5466.753996070186;5491.203775452577;5510.090835012218;6139.492138333381;6157.261128664544;6267.310595834455;6274.905876411857;6288.748450061992;6597.47220222381;6683.2982536833415;6697.7655782047395;6699.330066993261;6700.162479690039;6709.832254193635;6789.898151527731;6805.523613347068;6807.149106361705;6824.317568243186;6204.782832600928;6300.0234963580715;6316.715747791361;6317.1156038154695;6319.5576309658345;6787;6802.727890884309;6891.586504809728;6897.448204143657;6939.618320992394;6327.841803954938;6330.841728956813;6421.5825971311715;6584.407403333723;6603.900941485882;6653.800385988428;6723.294054124344;6745.190577130776;6750.088738020402;6764.695588698521;6648.734050638017;6660.067590537328;6667.966660166765;6673.866522669519;6687.565308254948;6445.291017988017;6538.1827271591055;6557.409833115043;6633.568818026813;6670.199576050905;5383.919241211385;5385;5386.8383948481605;5398.595105367034;5417.133258678593;4680.95319046812;4737.123632128068;4741.193997020515;4750.809967601326;4774.355462012584;6251.28110267316;6273.874522509615;6322.652254125976;6359.586626869239;6378.617282962944;5136.255242989746;5701.372848986608;6125.693715314216;6575.539712220098;6816.07982922309;6223.222097237845;6304.936950630435;6313.463355614732;6334;6366.522510811615;4960.851174464772;5797.072468405119;5799.420057994167;5941.821745347646;6018.187544681499;6745.156855393076;6869.210040845367;6870.831958566956;6933.514653974174;6953.860922781617;6772.356626120708;6820.727170913108;6846.246912839683;6868.416184624363;6870.793876183722;5899.646021238739;6967.233604303624;7020.122484689414;7055.329743674342;7056.223815380406;6437.645929473924;6533.281339052605;6684.63234522106;6722.226943901318;6733.663316834237;6713.160854893138;6777.661116944231;6778.389944904982;6818.249992500921;6819.147606549182;6304.401081897213;6324.051392291193;6610.504212184009;6626.337366263335;6632.767853125118;6517.478601711757;6622.63575503352;6625.17185351831;6625.4037136657935;6644.700988455627;6288.056791481315;6506.219253689587;6517;6522.119513865568;6528.086067950586;6474.643894585843;6495.220573531017;6555.246146693191;6575.27671956094;6577.2436169841085;6435.581687190318;6449.290578036506;6451.41937225652;6469.191351081115;6471.708773105316 16:48:57 INFO - PID 1649 | |1;ext.html;758.2190343945736;772.5673622771249;820.9917900820992;1243.4653099167354;1298.5455090718244;38753.61246387539;39931.800340998256;39939.20121597566;39968;40136.197276054496;40074.398884016766;40327.39508907369;40369.59630403695;40369.79815100928;40520;7707.460477766558;7855.1752066033105;8355.206255405727;8591.44155629882;8595.484270943733;8494.193051660082;8529.10444403338;8604.311655067611;8615.095414981432;8618.87071693922;8583.313334933191;8609.139086091403;8618.353623478233;8632.913670863305;8632.913670863305;8575.614097365633;8589.656413743443;8592.8711069334;8608.225259726592;8612.48325100496;8287.378446616527;8292.751217463485;8339.374546909006;8354.749357519284;8371.539565323905;37667.434988475194;37850.43224351637;37914.43128353077;37998;38037.23925521474;3170.429322721909;3563.572371315433;3577.83899724513;3590.6588874056924;3597.4244120940525;30483.847580761954;30754;30764.07707768748;30794;30796.38407231865;2948.3513627002026;2989.222802071477;2989.760819134464;3001.669816320203;3009.6839831817783;229876;234383;277237;297874;298398;47539.049219015425;47582.81042973953;47716.5228347719;47876.56370308896;47936.5206347939;50253.748731256106;50255;50498;50535.74732126316;50644.74677626662;14242.643933901529;14389.496367627293;14395.496157634432;14403.207823569595;14406.207658578669;9963.900360996297;9981.850272245922;10082.949585252027;11224.821393753702;11666.891645293683;12222.449989750483;13487.662808429868;13586.214825960851;13683.452661893412;13763.655908602364;5170.431252562215;5421.099711654246;6062.211912451353;6092.208012958376;6132.325444201134;1014.2545229256491;1027.2706378471369;1027.6505987964129;1059.2902755154066;1062.9202809789297;23314.7668523316;24517;24587.893544790528;24639.260822175005;24768.752312477005;1020.5509575786779;1135.2280449294558;1135.8353038809325;1167.2004676796253;1179.3454632678945;12425.31660758667;12566.937165314297;12593.874061259454;12662.303573303372;12671.303078330597;321519.3924030365;321907;322433;322608;324979;54308;54562.63593410196;54630.90738185214;54641;54708;1382.1430712957829;1518.8177418709824;1534.8618624324088;1567.6472793621344;1569.591906104421;23557.175498857458;23576.0569577215;23724.762752372255;23837.523249535257;23855.403614909766;37896.242075158894;37916.62083379241;37981.62018379781;38007.80996095113;38086.80956595199;269241;269738;270102;270527;270724;32657.020289391356;34053.65946340604;34110;34286.48570271398;34298.657013430544;43333;45580;45718;45789.31316030196;45841.77079114583;28922.42155156927;30260.394792104475;30560.541591876583;31360.843195783877;31521.369572608877;24108.87945560261;31275.53086703747;31375.05874823759;31572.684273157894;31604.525932110577;34476;36286.09284767902;37876.81061594785;37886.052848679006;37954;25086.62370064487;30249.160134378395;31316.843415783685;31319;31482.685173147976;23175.76824231736;24372.390690232885;24535.50928981375;24846.627300590866;25118.497630047663 16:48:57 INFO - PID 1649 | |2;jquery.html;402.30602211185703;432.7230572433641;437.95826681547834;441.82547893582034;456.9223232050549;20921.58156836864;21029.894850525747;21829.90850457479;21956.23153189639;22066.668999964993;22052.448688782788;22306.88846555765;22336.88831555844;22511;23129.768702313013;5949.434803693653;6128.3258841527395;6161.4146656067605;6209.813705588839;6210;5815.970920145393;6015.639061656293;6075.63546187228;6094.603850749687;6104.816855494342;5868.853278668046;5971.07448345505;5975;6020.969895150518;6050.788222412217;5305;5809.6804675742815;5948.643081415107;5999.910001349983;6011.368806275365;4972.353594032788;5162.74186290684;5320.255164277004;5344.946550534483;5412.296401467784;24996.125135620165;25056.24831255065;25058.87470562654;25226.11708590209;25239.62140567893;2001.35956493922;2120.7455105387453;2210.3589958912057;2231.152162178378;2239.193890199543;17953;17963.012034338106;18040.097995100194;18214.27142914282;18229.7265541017;1850.6021205440761;1852.5924296654714;2138.112683236455;2146.559955209194;2156.762756096828;161859;162665;164123;165270;165347;10625;12710.745785084431;12758;12839.935800320938;13017.934910325388;16170.919145404197;16308.021518708916;16466.09436481005;16507.83492165063;16551;8863.689770858118;9021.32340074497;9057.04900985387;9145.954270228607;9182.311326650528;8657.783555411039;8686.044535101133;8710.782230444163;8838.55807209637;8871.512066836256;7071.469639777038;7503.836905279691;7808.921910780819;7916.564588947548;7938.9206107939335;2182.90176942038;2200.1749343996003;2203.1958335207532;2204.481946742514;2397.2328854766306;932.6010983524712;962.1869520255373;975.7024107647094;986.4820968991337;1008.4201584089178;20132.094055767524;20570.97145142737;20635.174593016112;20845.68731469029;20849.1660333588;993.9155171810332;1006.8540061691014;1023.99488002561;1026.8716410448694;1033.9069483746498;9332.880054393381;10410.063094321547;10686.465676716132;10689.679309620724;10699.786004280026;506498;515411;519858;529036;532951;116423;124327;130678.34660826635;134258;134398.98401523984;1139.3391832736993;1174.3306315400312;1187.8455800745849;1191.618682021762;1194.062660811259;17598;18204.635907282045;18217.635647287247;18227.81013322637;18468.168932397806;55457.25046708666;56465.87068258529;57128.57178570403;57147;57415;59873.70063149656;76132.61933690296;77306.84039739295;78370.6081469589;79902;8256;8849.469031857852;8938.150875667061;9270.397424167408;9314.534273286174;13589.592312230649;14645.92677036608;14716.484923027643;14754.557363279118;15012.249387530797;12264.509419623115;12323.753524929272;12415.689607759878;12481;12519.248845069325;9645.276604254568;11191.32852028881;11522.596709115141;11590.88409115898;11892.464839082435;12557.93721031389;12629.216844939932;12638.747225055631;12680.556180533637;12698.682532936751;7775.416843736629;8784.715973846718;8839;8881.644734210819;9211.723648290563;10511.421871797125;12082.516699331929;12164.2701437914;12404.19372740769;12448 16:48:57 INFO - PID 1649 | |3;mootools.html;1415.4267521653728;1466.5380405172364;1477.0399240493678;1487.9925600371996;1492.306232426196;11417.600383986552;11583.942080289587;11872.94063529681;11904.107191960606;11997.940010299959;11667.299962002286;11843.407829608528;11852.703682407944;11867.465964031619;11886.821697674543;2506.285708573057;2562.128876182098;2563.218218443374;2575.9227223183334;2577.5875859862426;2509.2095989763257;2568.7174410814796;2572.8070394720376;2575.562154433751;2579.651747014148;2520.4959008198343;2535.353484861367;2539.11131104113;2555.501677172943;2557.539642864284;2017.5258814178655;2473.4682043360763;2493.2021753039035;2495.438526331579;2496.9750302496923;2102;2443.4135807406274;2497.3382053755586;2527.0776166699025;2531.2153232497985;11500.367479788609;11531.250468719507;11644.534218631245;11646.941765291203;11661.425707529459;6370.203724534434;6529.673516324165;6618.4705223582;6619.735210591571;6631.436327912085;3250.6099268087983;4213.262679031188;4278.8716338509585;4286.87139385819;4298.054428025829;1123.9269447485888;1160.1994623709613;1217.385220463669;1274.241826113461;1282.8011658192995;15124.54626361211;15441.15073670959;16160.27278772458;16181.676366472604;16262.349506019746;5355.678659280456;5418.458154184629;5568.359638641527;5607.887842243133;5654.321481422256;11542.249753765978;11588.304701717923;11594.826077608674;11626.941865290619;11633.825492617618;6450.8064758057335;6484.254310754323;6485.059666348353;6489.091527186198;6515.7067931943175;5644.604877658607;6101.908471372933;6191.1641928339095;6219.689015549205;6275.341089185661;4308.827646894089;4376.584224498668;4397.31841564558;4402.119576084796;4435.489918659331;1267.2269915351628;1271.453275091701;1272.63729836996;1283.076185146693;1285.8264134341932;788.1960400391683;796.6056801883105;822.3256929317902;823.9464434811719;846.9449485783405;6056.727447264883;6112.388761123852;6247.75008999644;6372.522060845455;6484.703059388047;605.9606125601822;609.740860134441;615.2248167309223;615.2432508015077;622.4211483320555;3473.3921563726753;3644.034330902313;3885.8445662173763;3933.645971862546;3983.5418926823786;10508.579656813641;14358.641033974234;14390.784138237936;14558.563243102724;14651.487197948021;14200.0769949955;14530.636734081521;14531.92734036323;14539.709205815825;14587.708245835236;8299.170082991652;8440.662373505114;8488.83022339562;8564.458397488512;8565.314774818;6362.968185159045;6406.455451286693;6695.799126026227;7100.325469080429;7169.7132114716915;12520.49918003306;12565.87434125647;12925.676858078623;13017.21896686202;13146.934265328995;13276.070675052653;13336;13484.59546213576;13572.253526055767;13733.45066197341;9108.863367049633;9249.722508324761;9272.979972202917;9311.208547273558;9316.6273349068;10356;10593.576256949944;10663.89336106629;10670.039696427359;10726.51730672106;9121.859767529058;9126.224270936778;9177.54112294396;9178.908210917805;9289.349745518019;8814.250788683034;8892.822143557221;8910.509921954355;8977.640894364153;9013.96339420975;7117.501774875709;7396.890466430079;8089;8136.593170341577;8254.917450825655;6008.429199226067;6122.663253521099;6144.8771024580155;6172.907406388818;6191.318954914866;6057.697595016916;6106.603070800384;6156.538259630456;6168.321484636776;6184.319724830175 16:48:57 INFO - PID 1649 | |4;prototype.html;362.27364134909504;381.3383779143186;399.73217943977534;401.27369461275094;401.63651895035;6019.398060193978;6182.8252631999885;6317.873642527146;6366.395192456721;6371.745130194799;4981.427135879375;6170.259568851744;6255.374462553742;6291.748330066792;6304.968475157618;4301.720388174775;4487.663425243103;4613.723176609397;4615.861524154281;4622.815087396509;4021.678265738734;4576.1762882681105;4583.702059366147;4596.965682721378;4600.263957766758;3523.3834079036064;4478.104379124172;4549;4567.584048944837;4619.74591397473;3550.804705741183;3648.288583726161;4044.47421835161;4479.3057076153245;4524.5701658342405;3250.7074363307415;3849.711271654637;3865.4781604483314;3868.2843673920415;3879.8254078566533;6103.786367477162;6158.476529494998;6175.505959523227;6201.782937597162;6202.875942481125;4553.544645535453;4871.4195066626;4949.529794669501;5305.44292849253;5474.835754927359;6750.1562304711915;6759.155105611799;6778.830529236809;6779.423748981342;6798.912174052128;3725.4411838224214;4342.30523116303;4441.444819397575;4444.5555194561575;4453.198424283628;5943.9598070337515;6134.846628834225;6449.9035164022225;7102.893456598155;7143.642817859139;3834.559025712079;4308.978455107705;4462.776861156929;4495.685302028826;4504.909901801946;3694.5751238607368;4185.100203456272;4266.338717498793;4593.196190666687;4601.309803529497;5377.838664840061;5445.891082178334;5450.5912056595125;5476.67139971603;5490.368607610176;5160.354955630546;6388.105665206875;6782.050512928194;6884.690188941409;6891.310868913169;1319.4062671797728;1545.3123360104871;1558.2520390212762;1558.9844101559065;1561.6017915431719;969.8787651543557;999.8601594182637;1005.4218824176128;1015.8679371681634;1032.3650954662978;5004.2493625956395;6367.968160159169;6373.71318290678;6459.580127291711;6465.612063276219;6175.59858609189;6194.938050619527;6714.529982901247;6784;7054.08296921397;6330.341256672116;6393.817143828313;6434.747838256572;6490.740370385131;6502.219733631994;3222.2749881276995;3423.7089847363254;3596.011096948308;3626.3472574936764;3687.7277339317893;5650.604457688005;6331.733653269364;7246;7251.601161936039;7282.490225684257;7159.463040272;7223.349898509159;7241.855162896818;7288.489805713549;7317.658349328271;5560.777568897199;6023.819285421444;6134.5092392608485;7204;7204.4956853019785;5637.774489020393;6296.166724973688;6532.085508028785;6754.696038678173;7013.333733295329;6721.395074443323;6883.141551781091;6886.965565172142;6997;7019.19279282889;6466.579672321284;6735.865282694416;6756.290589488033;7054.929450705633;7357.668904899186;3797.848086076526;3832.9616703832603;4526.20791361517;4553.2259515881715;4558.635309175324;5831.891940531285;6300.637261027115;6869.313068693091;7128.180259270043;7183.604901730246;5916.408359164049;6708.932910670831;6910.7581234656545;6911.688973996082;6943.236244013256;6801.795946121624;6812.96593517049;6944.375006249462;6947;6958.269381714847;6199.443409094222;6199.5040396767245;6426.839329016625;6762.951742479923;6863.1517920062615;5097.235414687826;6080.452759251689;6611.702473388613;7010.773114704804;7024.262452442625;4975.402951645826;6750;6751.156105486814;6752.966235168793;6771.559848609824 16:48:57 INFO - PID 1649 | |5;yui.html;604.9334573196945;614.9262088549375;676.41828027896;715.2418436457356;849.6304107713144;16071.59821004474;16495.010299382044;16505.83494165058;18053.90973045133;19291.324803631876;17740;18048.54878628035;18248.361307354244;18322.72515912259;18625.975571343606;1002.3685078400615;1033.736397218712;1034.0693375961657;1036.1037702387418;1040.7242080848582;990.5988074829705;1018.8777346718405;1038.833786594145;1045.132539991805;1046.4244665434019;1074.414444127952;1078.3422112511362;1078.4607696151925;1082.155918383662;1082.3289560472479;1069.7806949575318;1073.98508409553;1075.3547871277249;1076.2358725305044;1076.445630500293;782.1708988472237;803.0203152154421;803.1727320859524;803.2409373142383;804.0512195609238;16904.323827046905;17285;17489.7376539352;17539.73690394645;17869.46391608254;17093;17219.139043047922;17319.047452390114;17378.3048678054;17514.38699645506;14042.508512202025;14325.928370358186;14360.712785744332;14510.927445362706;14536.127832330094;3618.873339433107;3622.8913132606062;3648.0332711831384;3649.635036496329;3654.086478380405;125060;126113.73886261112;127702.3614881929;127711;128736.35631821875;13373.398197081155;13378.732425351438;13410.530631428048;13509.527166549124;13528;21505.462363441038;25340.493190136094;25421.745782542308;25652.74347256541;25720;7611.1627720950655;7916.84166316682;7993.480423772437;8003.8799418008775;8007.559584222924;3950.545687245946;3987.5813039631003;3998.0804414984136;4023.1551374211745;4035.9394609080887;4340.804663790137;4359.607635312837;4449.822007119744;4450.955490445054;4458.5318541553315;869.5956380283199;895.9417637853649;898.9460632362079;900.1493588558749;903.1555495611624;538.8625900395374;657.3525077798365;659.0740010285557;673.3804899492478;688.8243497908002;10928.398938058483;11523.366214858095;11919;11982;12194.57318993831;649.2630863969429;651.9087327774115;655.9603029198678;658.3021996683531;674.7604600366898;4916.582090522345;5318.468153184651;5343.064963631349;5542.695151766611;5608.49523542875;110034.44982775034;111848.44075779569;112463;113344;113576.86423135662;83157.50527484185;94743;95979;98014;98366;63971.360286397474;77123;81387.3722525558;81586.59206704047;82846.58576707078;11003.559857605605;12048.554173499237;12686.746265074462;12732.172408793398;12770.553030643883;28205.858970705016;30207.942722004624;33136.33727325488;37768.05579860525;39864.60135398609;62493;68572;68612.656936715;69583.30416695768;69680;16328.836711632732;16397.180140992663;16435.342586296414;16443.260053297392;16470.588235294214;17311.3941012064;17559.736603951205;17733.113344332472;17988.460346189637;18300.450986470427;16017.439389621308;16230.67538649244;16414.917925410296;16645.50063498097;16679.66640667204;13984.440622375398;15844.841551584337;16478.34086636521;16485.670286593962;16554.337826486804;15436.151011694465;15655.295511702228;16127;16163.919180404493;16191.433299834449;11233.775324493301;11467.770644587228;11643.76712465729;12295.877041229473;12338.3830808461;12223.388830558615;12282.078844086907;13082.018848586204;13654.590362289147;13667.31663416845 16:48:57 INFO - PID 1649 | __end_tp_report 16:48:57 INFO - PID 1649 | __start_cc_report 16:48:57 INFO - PID 1649 | _x_x_mozilla_cycle_collect,406 16:48:57 INFO - PID 1649 | __end_cc_report 16:48:57 INFO - PID 1649 | __startTimestamp1495583337890__endTimestamp 16:48:57 INFO - PID 1649 | 16:48:57 INFO - PID 1649 | ------- Summary: start ------- 16:48:57 INFO - PID 1649 | Number of tests: 6 16:48:57 INFO - PID 1649 | 16:48:57 INFO - PID 1649 | [#0] dojo.html Cycles:165 Average:6155.17 Median:6366.52 stddev:747.94 (11.7%) stddev-sans-first:714.14 16:48:57 INFO - PID 1649 | Values: 3229.4 3361.2 3406.6 3595.2 3599.2 5100.6 5252.9 5881.6 6138.7 6143.6 6154.6 6167.4 6190.2 6243.6 6262.0 5298.8 5436.4 5461.2 5477.8 5547.4 5553.2 5566.7 5568.0 5573.6 5579.6 5389.6 5463.2 5466.3 5514.0 5539.7 5505.6 5514.7 5548.3 5557.9 5570.6 5441.3 5452.3 5466.8 5491.2 5510.1 6139.5 6157.3 6267.3 6274.9 6288.7 6597.5 6683.3 6697.8 6699.3 6700.2 6709.8 6789.9 6805.5 6807.1 6824.3 6204.8 6300.0 6316.7 6317.1 6319.6 6787.0 6802.7 6891.6 6897.4 6939.6 6327.8 6330.8 6421.6 6584.4 6603.9 6653.8 6723.3 6745.2 6750.1 6764.7 6648.7 6660.1 6668.0 6673.9 6687.6 6445.3 6538.2 6557.4 6633.6 6670.2 5383.9 5385.0 5386.8 5398.6 5417.1 4681.0 4737.1 4741.2 4750.8 4774.4 6251.3 6273.9 6322.7 6359.6 6378.6 5136.3 5701.4 6125.7 6575.5 6816.1 6223.2 6304.9 6313.5 6334.0 6366.5 4960.9 5797.1 5799.4 5941.8 6018.2 6745.2 6869.2 6870.8 6933.5 6953.9 6772.4 6820.7 6846.2 6868.4 6870.8 5899.6 6967.2 7020.1 7055.3 7056.2 6437.6 6533.3 6684.6 6722.2 6733.7 6713.2 6777.7 6778.4 6818.2 6819.1 6304.4 6324.1 6610.5 6626.3 6632.8 6517.5 6622.6 6625.2 6625.4 6644.7 6288.1 6506.2 6517.0 6522.1 6528.1 6474.6 6495.2 6555.2 6575.3 6577.2 6435.6 6449.3 6451.4 6469.2 6471.7 16:48:57 INFO - PID 1649 | 16:48:57 INFO - PID 1649 | [#1] ext.html Cycles:180 Average:44132.59 Median:178818.01 stddev:75558.86 (42.3%) stddev-sans-first:75700.64 16:48:57 INFO - PID 1649 | Values: 758.2 772.6 821.0 1243.5 1298.5 38753.6 39931.8 39939.2 39968.0 40136.2 40074.4 40327.4 40369.6 40369.8 40520.0 7707.5 7855.2 8355.2 8591.4 8595.5 8494.2 8529.1 8604.3 8615.1 8618.9 8583.3 8609.1 8618.4 8632.9 8632.9 8575.6 8589.7 8592.9 8608.2 8612.5 8287.4 8292.8 8339.4 8354.7 8371.5 37667.4 37850.4 37914.4 37998.0 38037.2 3170.4 3563.6 3577.8 3590.7 3597.4 30483.8 30754.0 30764.1 30794.0 30796.4 2948.4 2989.2 2989.8 3001.7 3009.7 229876.0 234383.0 277237.0 297874.0 298398.0 47539.0 47582.8 47716.5 47876.6 47936.5 50253.7 50255.0 50498.0 50535.7 50644.7 14242.6 14389.5 14395.5 14403.2 14406.2 9963.9 9981.9 10082.9 11224.8 11666.9 12222.4 13487.7 13586.2 13683.5 13763.7 5170.4 5421.1 6062.2 6092.2 6132.3 1014.3 1027.3 1027.7 1059.3 1062.9 23314.8 24517.0 24587.9 24639.3 24768.8 1020.6 1135.2 1135.8 1167.2 1179.3 12425.3 12566.9 12593.9 12662.3 12671.3 321519.4 321907.0 322433.0 322608.0 324979.0 54308.0 54562.6 54630.9 54641.0 54708.0 1382.1 1518.8 1534.9 1567.6 1569.6 23557.2 23576.1 23724.8 23837.5 23855.4 37896.2 37916.6 37981.6 38007.8 38086.8 269241.0 269738.0 270102.0 270527.0 270724.0 32657.0 34053.7 34110.0 34286.5 34298.7 43333.0 45580.0 45718.0 45789.3 45841.8 28922.4 30260.4 30560.5 31360.8 31521.4 24108.9 31275.5 31375.1 31572.7 31604.5 34476.0 36286.1 37876.8 37886.1 37954.0 25086.6 30249.2 31316.8 31319.0 31482.7 23175.8 24372.4 24535.5 24846.6 25118.5 16:48:57 INFO - PID 1649 | 16:48:57 INFO - PID 1649 | [#2] jquery.html Cycles:180 Average:34826.70 Median:12258.62 stddev:89107.00 (726.9%) stddev-sans-first:89319.48 16:48:57 INFO - PID 1649 | Values: 402.3 432.7 438.0 441.8 456.9 20921.6 21029.9 21829.9 21956.2 22066.7 22052.4 22306.9 22336.9 22511.0 23129.8 5949.4 6128.3 6161.4 6209.8 6210.0 5816.0 6015.6 6075.6 6094.6 6104.8 5868.9 5971.1 5975.0 6021.0 6050.8 5305.0 5809.7 5948.6 5999.9 6011.4 4972.4 5162.7 5320.3 5344.9 5412.3 24996.1 25056.2 25058.9 25226.1 25239.6 2001.4 2120.7 2210.4 2231.2 2239.2 17953.0 17963.0 18040.1 18214.3 18229.7 1850.6 1852.6 2138.1 2146.6 2156.8 161859.0 162665.0 164123.0 165270.0 165347.0 10625.0 12710.7 12758.0 12839.9 13017.9 16170.9 16308.0 16466.1 16507.8 16551.0 8863.7 9021.3 9057.0 9146.0 9182.3 8657.8 8686.0 8710.8 8838.6 8871.5 7071.5 7503.8 7808.9 7916.6 7938.9 2182.9 2200.2 2203.2 2204.5 2397.2 932.6 962.2 975.7 986.5 1008.4 20132.1 20571.0 20635.2 20845.7 20849.2 993.9 1006.9 1024.0 1026.9 1033.9 9332.9 10410.1 10686.5 10689.7 10699.8 506498.0 515411.0 519858.0 529036.0 532951.0 116423.0 124327.0 130678.3 134258.0 134399.0 1139.3 1174.3 1187.8 1191.6 1194.1 17598.0 18204.6 18217.6 18227.8 18468.2 55457.3 56465.9 57128.6 57147.0 57415.0 59873.7 76132.6 77306.8 78370.6 79902.0 8256.0 8849.5 8938.2 9270.4 9314.5 13589.6 14645.9 14716.5 14754.6 15012.2 12264.5 12323.8 12415.7 12481.0 12519.2 9645.3 11191.3 11522.6 11590.9 11892.5 12557.9 12629.2 12638.7 12680.6 12698.7 7775.4 8784.7 8839.0 8881.6 9211.7 10511.4 12082.5 12164.3 12404.2 12448.0 16:48:57 INFO - PID 1649 | 16:48:57 INFO - PID 1649 | [#3] mootools.html Cycles:180 Average:6981.23 Median:3362.00 stddev:4373.36 (130.1%) stddev-sans-first:4365.63 16:48:57 INFO - PID 1649 | Values: 1415.4 1466.5 1477.0 1488.0 1492.3 11417.6 11583.9 11872.9 11904.1 11997.9 11667.3 11843.4 11852.7 11867.5 11886.8 2506.3 2562.1 2563.2 2575.9 2577.6 2509.2 2568.7 2572.8 2575.6 2579.7 2520.5 2535.4 2539.1 2555.5 2557.5 2017.5 2473.5 2493.2 2495.4 2497.0 2102.0 2443.4 2497.3 2527.1 2531.2 11500.4 11531.3 11644.5 11646.9 11661.4 6370.2 6529.7 6618.5 6619.7 6631.4 3250.6 4213.3 4278.9 4286.9 4298.1 1123.9 1160.2 1217.4 1274.2 1282.8 15124.5 15441.2 16160.3 16181.7 16262.3 5355.7 5418.5 5568.4 5607.9 5654.3 11542.2 11588.3 11594.8 11626.9 11633.8 6450.8 6484.3 6485.1 6489.1 6515.7 5644.6 6101.9 6191.2 6219.7 6275.3 4308.8 4376.6 4397.3 4402.1 4435.5 1267.2 1271.5 1272.6 1283.1 1285.8 788.2 796.6 822.3 823.9 846.9 6056.7 6112.4 6247.8 6372.5 6484.7 606.0 609.7 615.2 615.2 622.4 3473.4 3644.0 3885.8 3933.6 3983.5 10508.6 14358.6 14390.8 14558.6 14651.5 14200.1 14530.6 14531.9 14539.7 14587.7 8299.2 8440.7 8488.8 8564.5 8565.3 6363.0 6406.5 6695.8 7100.3 7169.7 12520.5 12565.9 12925.7 13017.2 13146.9 13276.1 13336.0 13484.6 13572.3 13733.5 9108.9 9249.7 9273.0 9311.2 9316.6 10356.0 10593.6 10663.9 10670.0 10726.5 9121.9 9126.2 9177.5 9178.9 9289.3 8814.3 8892.8 8910.5 8977.6 9014.0 7117.5 7396.9 8089.0 8136.6 8254.9 6008.4 6122.7 6144.9 6172.9 6191.3 6057.7 6106.6 6156.5 6168.3 6184.3 16:48:57 INFO - PID 1649 | 16:48:57 INFO - PID 1649 | [#4] prototype.html Cycles:180 Average:5350.49 Median:6164.37 stddev:1752.47 (28.4%) stddev-sans-first:1716.92 16:48:57 INFO - PID 1649 | Values: 362.3 381.3 399.7 401.3 401.6 6019.4 6182.8 6317.9 6366.4 6371.7 4981.4 6170.3 6255.4 6291.7 6305.0 4301.7 4487.7 4613.7 4615.9 4622.8 4021.7 4576.2 4583.7 4597.0 4600.3 3523.4 4478.1 4549.0 4567.6 4619.7 3550.8 3648.3 4044.5 4479.3 4524.6 3250.7 3849.7 3865.5 3868.3 3879.8 6103.8 6158.5 6175.5 6201.8 6202.9 4553.5 4871.4 4949.5 5305.4 5474.8 6750.2 6759.2 6778.8 6779.4 6798.9 3725.4 4342.3 4441.4 4444.6 4453.2 5944.0 6134.8 6449.9 7102.9 7143.6 3834.6 4309.0 4462.8 4495.7 4504.9 3694.6 4185.1 4266.3 4593.2 4601.3 5377.8 5445.9 5450.6 5476.7 5490.4 5160.4 6388.1 6782.1 6884.7 6891.3 1319.4 1545.3 1558.3 1559.0 1561.6 969.9 999.9 1005.4 1015.9 1032.4 5004.2 6368.0 6373.7 6459.6 6465.6 6175.6 6194.9 6714.5 6784.0 7054.1 6330.3 6393.8 6434.7 6490.7 6502.2 3222.3 3423.7 3596.0 3626.3 3687.7 5650.6 6331.7 7246.0 7251.6 7282.5 7159.5 7223.3 7241.9 7288.5 7317.7 5560.8 6023.8 6134.5 7204.0 7204.5 5637.8 6296.2 6532.1 6754.7 7013.3 6721.4 6883.1 6887.0 6997.0 7019.2 6466.6 6735.9 6756.3 7054.9 7357.7 3797.8 3833.0 4526.2 4553.2 4558.6 5831.9 6300.6 6869.3 7128.2 7183.6 5916.4 6708.9 6910.8 6911.7 6943.2 6801.8 6813.0 6944.4 6947.0 6958.3 6199.4 6199.5 6426.8 6763.0 6863.2 5097.2 6080.5 6611.7 7010.8 7024.3 4975.4 6750.0 6751.2 6753.0 6771.6 16:48:57 INFO - PID 1649 | 16:48:57 INFO - PID 1649 | [#5] yui.html Cycles:180 Average:22205.97 Median:17527.06 stddev:31718.80 (181.0%) stddev-sans-first:31766.31 16:48:57 INFO - PID 1649 | Values: 604.9 614.9 676.4 715.2 849.6 16071.6 16495.0 16505.8 18053.9 19291.3 17740.0 18048.5 18248.4 18322.7 18626.0 1002.4 1033.7 1034.1 1036.1 1040.7 990.6 1018.9 1038.8 1045.1 1046.4 1074.4 1078.3 1078.5 1082.2 1082.3 1069.8 1074.0 1075.4 1076.2 1076.4 782.2 803.0 803.2 803.2 804.1 16904.3 17285.0 17489.7 17539.7 17869.5 17093.0 17219.1 17319.0 17378.3 17514.4 14042.5 14325.9 14360.7 14510.9 14536.1 3618.9 3622.9 3648.0 3649.6 3654.1 125060.0 126113.7 127702.4 127711.0 128736.4 13373.4 13378.7 13410.5 13509.5 13528.0 21505.5 25340.5 25421.7 25652.7 25720.0 7611.2 7916.8 7993.5 8003.9 8007.6 3950.5 3987.6 3998.1 4023.2 4035.9 4340.8 4359.6 4449.8 4451.0 4458.5 869.6 895.9 898.9 900.1 903.2 538.9 657.4 659.1 673.4 688.8 10928.4 11523.4 11919.0 11982.0 12194.6 649.3 651.9 656.0 658.3 674.8 4916.6 5318.5 5343.1 5542.7 5608.5 110034.4 111848.4 112463.0 113344.0 113576.9 83157.5 94743.0 95979.0 98014.0 98366.0 63971.4 77123.0 81387.4 81586.6 82846.6 11003.6 12048.6 12686.7 12732.2 12770.6 28205.9 30207.9 33136.3 37768.1 39864.6 62493.0 68572.0 68612.7 69583.3 69680.0 16328.8 16397.2 16435.3 16443.3 16470.6 17311.4 17559.7 17733.1 17988.5 18300.5 16017.4 16230.7 16414.9 16645.5 16679.7 13984.4 15844.8 16478.3 16485.7 16554.3 15436.2 15655.3 16127.0 16163.9 16191.4 11233.8 11467.8 11643.8 12295.9 12338.4 12223.4 12282.1 13082.0 13654.6 13667.3 16:48:57 INFO - PID 1649 | -------- Summary: end -------- 16:48:57 INFO - PID 1649 | 16:48:58 INFO - TEST-INFO | 1649: exit 0 16:48:58 INFO - TEST-OK | dromaeo_css | took 1143154ms 16:48:58 INFO - TEST-START | kraken 16:48:58 INFO - operating with platform_type : mac_ 16:48:58 INFO - Initialising browser for kraken test... 16:48:58 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmp2Ep62J/profile 16:48:58 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground) 16:49:32 INFO - TEST-INFO | 1659: killed by SIGTERM 16:49:32 INFO - Browser initialized. 16:49:32 INFO - Running cycle 1/1 for kraken test... 16:49:32 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmp2Ep62J/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:49:34 INFO - PID 1663 | 2017-05-23 16:49:34.236 plugin-container[1664:10603] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x994f, name = 'com.apple.tsm.portname' 16:49:34 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:49:34 INFO - PID 1663 | 2017-05-23 16:49:34.237 plugin-container[1664:10603] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 16:49:34 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:49:34 INFO - PID 1663 | 2017-05-23 16:49:34.237 plugin-container[1664:10603] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:49:34 INFO - PID 1663 | 2017-05-23 16:49:34.722 plugin-container[1665:10646] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 16:49:34 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:49:34 INFO - PID 1663 | 2017-05-23 16:49:34.723 plugin-container[1665:10646] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x481b, name = 'com.apple.CFPasteboardClient' 16:49:34 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:49:34 INFO - PID 1663 | 2017-05-23 16:49:34.723 plugin-container[1665:10646] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:49:44 INFO - PID 1663 | RSS: Main: 248541184 16:49:44 INFO - PID 1663 | 16:49:46 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection) 16:49:47 INFO - PID 1663 | RSS: Main: 263397376 16:49:47 INFO - PID 1663 | 16:49:50 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-dft) 16:49:50 INFO - PID 1663 | RSS: Main: 272154624 16:49:50 INFO - PID 1663 | 16:49:54 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-fft) 16:49:54 INFO - PID 1663 | RSS: Main: 274784256 16:49:54 INFO - PID 1663 | 16:49:56 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator) 16:49:57 INFO - PID 1663 | RSS: Main: 276856832 16:49:57 INFO - PID 1663 | 16:49:59 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:50:00 INFO - PID 1663 | RSS: Main: 277372928 16:50:00 INFO - PID 1663 | 16:50:03 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom) 16:50:03 INFO - PID 1663 | RSS: Main: 282865664 16:50:03 INFO - PID 1663 | 16:50:06 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate) 16:50:07 INFO - PID 1663 | RSS: Main: 285700096 16:50:07 INFO - PID 1663 | 16:50:10 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial) 16:50:11 INFO - PID 1663 | RSS: Main: 284737536 16:50:11 INFO - PID 1663 | 16:50:12 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:50:13 INFO - PID 1663 | RSS: Main: 284483584 16:50:13 INFO - PID 1663 | 16:50:15 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:50:15 INFO - PID 1663 | RSS: Main: 284401664 16:50:15 INFO - PID 1663 | 16:50:18 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:50:18 INFO - PID 1663 | RSS: Main: 284479488 16:50:18 INFO - PID 1663 | 16:50:21 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:50:22 INFO - PID 1663 | RSS: Main: 284266496 16:50:22 INFO - PID 1663 | 16:50:25 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:50:25 INFO - PID 1663 | RSS: Main: 284213248 16:50:25 INFO - PID 1663 | 16:50:27 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49226/tests/kraken/driver.html?testName=ai-astar) 16:50:27 INFO - PID 1663 | RSS: Main: 286097408 16:50:27 INFO - PID 1663 | 16:50:27 INFO - PID 1663 | __start_tp_report 16:50:27 INFO - PID 1663 | _x_x_mozilla_page_load 16:50:27 INFO - PID 1663 | _x_x_mozilla_page_load_details 16:50:27 INFO - PID 1663 | |i|pagename|runs| 16:50:27 INFO - PID 1663 | |0;ai-astar;101;116;114;114;113;116;130;152;114;114 16:50:27 INFO - PID 1663 | |1;audio-beat-detection;138;110;109;169;144;144;92;94;129;135 16:50:27 INFO - PID 1663 | |2;audio-dft;195;198;195;211;220;228;205;273;239;207 16:50:27 INFO - PID 1663 | |3;audio-fft;81;55;56;84;57;65;85;74;80;63 16:50:27 INFO - PID 1663 | |4;audio-oscillator;87;68;95;104;79;71;85;70;77;116 16:50:27 INFO - PID 1663 | |5;imaging-gaussian-blur;90;88;88;89;90;88;89;78;130;134 16:50:27 INFO - PID 1663 | |6;imaging-darkroom;96;96;96;150;98;97;96;96;96;96 16:50:27 INFO - PID 1663 | |7;imaging-desaturate;118;83;136;107;142;81;144;77;133;136 16:50:27 INFO - PID 1663 | |8;json-parse-financial;50;51;51;49;51;50;51;52;51;52 16:50:27 INFO - PID 1663 | |9;json-stringify-tinderbox;54;65;70;81;65;58;56;55;55;59 16:50:27 INFO - PID 1663 | |10;stanford-crypto-aes;86;86;88;87;88;103;109;85;126;88 16:50:27 INFO - PID 1663 | |11;stanford-crypto-ccm;139;138;137;136;135;136;144;140;148;149 16:50:27 INFO - PID 1663 | |12;stanford-crypto-pbkdf2;172;185;181;184;186;185;189;183;183;189 16:50:27 INFO - PID 1663 | |13;stanford-crypto-sha256-iterative;70;82;63;70;70;70;66;70;71;69 16:50:27 INFO - PID 1663 | __end_tp_report 16:50:27 INFO - PID 1663 | __start_cc_report 16:50:27 INFO - PID 1663 | _x_x_mozilla_cycle_collect,1071 16:50:27 INFO - PID 1663 | __end_cc_report 16:50:27 INFO - PID 1663 | __startTimestamp1495583427864__endTimestamp 16:50:27 INFO - PID 1663 | 16:50:27 INFO - PID 1663 | ------- Summary: start ------- 16:50:27 INFO - PID 1663 | Number of tests: 14 16:50:27 INFO - PID 1663 | 16:50:27 INFO - PID 1663 | [#0] ai-astar Cycles:10 Average:118.40 Median:115.00 stddev:13.68 (11.9%) stddev-sans-first:12.98 16:50:27 INFO - PID 1663 | Values: 101.0 116.0 114.0 114.0 113.0 116.0 130.0 152.0 114.0 114.0 16:50:27 INFO - PID 1663 | 16:50:27 INFO - PID 1663 | [#1] audio-beat-detection Cycles:10 Average:126.40 Median:144.00 stddev:24.62 (17.1%) stddev-sans-first:25.75 16:50:27 INFO - PID 1663 | Values: 138.0 110.0 109.0 169.0 144.0 144.0 92.0 94.0 129.0 135.0 16:50:27 INFO - PID 1663 | 16:50:27 INFO - PID 1663 | [#2] audio-dft Cycles:10 Average:217.10 Median:215.50 stddev:24.40 (11.3%) stddev-sans-first:24.54 16:50:27 INFO - PID 1663 | Values: 195.0 198.0 195.0 211.0 220.0 228.0 205.0 273.0 239.0 207.0 16:50:27 INFO - PID 1663 | 16:50:27 INFO - PID 1663 | [#3] audio-fft Cycles:10 Average:70.00 Median:77.00 stddev:12.12 (15.7%) stddev-sans-first:12.18 16:50:27 INFO - PID 1663 | Values: 81.0 55.0 56.0 84.0 57.0 65.0 85.0 74.0 80.0 63.0 16:50:27 INFO - PID 1663 | 16:50:27 INFO - PID 1663 | [#4] audio-oscillator Cycles:10 Average:85.20 Median:78.00 stddev:15.76 (20.2%) stddev-sans-first:16.70 16:50:27 INFO - PID 1663 | Values: 87.0 68.0 95.0 104.0 79.0 71.0 85.0 70.0 77.0 116.0 16:50:27 INFO - PID 1663 | 16:50:27 INFO - PID 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:96.40 Median:88.50 stddev:19.10 (21.6%) stddev-sans-first:20.12 16:50:27 INFO - PID 1663 | Values: 90.0 88.0 88.0 89.0 90.0 88.0 89.0 78.0 130.0 134.0 16:50:27 INFO - PID 1663 | 16:50:27 INFO - PID 1663 | [#6] imaging-darkroom Cycles:10 Average:101.70 Median:96.00 stddev:16.98 (17.7%) stddev-sans-first:17.89 16:50:27 INFO - PID 1663 | Values: 96.0 96.0 96.0 150.0 98.0 97.0 96.0 96.0 96.0 96.0 16:50:27 INFO - PID 1663 | 16:50:27 INFO - PID 1663 | [#7] imaging-desaturate Cycles:10 Average:115.70 Median:143.00 stddev:26.81 (18.7%) stddev-sans-first:28.42 16:50:27 INFO - PID 1663 | Values: 118.0 83.0 136.0 107.0 142.0 81.0 144.0 77.0 133.0 136.0 16:50:27 INFO - PID 1663 | 16:50:27 INFO - PID 1663 | [#8] json-parse-financial Cycles:10 Average:50.80 Median:51.00 stddev:0.92 (1.8%) stddev-sans-first:0.93 16:50:27 INFO - PID 1663 | Values: 50.0 51.0 51.0 49.0 51.0 50.0 51.0 52.0 51.0 52.0 16:50:27 INFO - PID 1663 | 16:50:27 INFO - PID 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:61.80 Median:62.00 stddev:8.60 (13.9%) stddev-sans-first:8.65 16:50:27 INFO - PID 1663 | Values: 54.0 65.0 70.0 81.0 65.0 58.0 56.0 55.0 55.0 59.0 16:50:27 INFO - PID 1663 | 16:50:27 INFO - PID 1663 | [#10] stanford-crypto-aes Cycles:10 Average:94.60 Median:86.50 stddev:13.71 (15.9%) stddev-sans-first:14.19 16:50:27 INFO - PID 1663 | Values: 86.0 86.0 88.0 87.0 88.0 103.0 109.0 85.0 126.0 88.0 16:50:27 INFO - PID 1663 | 16:50:27 INFO - PID 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:140.20 Median:139.50 stddev:5.07 (3.6%) stddev-sans-first:5.36 16:50:27 INFO - PID 1663 | Values: 139.0 138.0 137.0 136.0 135.0 136.0 144.0 140.0 148.0 149.0 16:50:27 INFO - PID 1663 | 16:50:27 INFO - PID 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.70 Median:185.00 stddev:4.83 (2.6%) stddev-sans-first:2.69 16:50:27 INFO - PID 1663 | Values: 172.0 185.0 181.0 184.0 186.0 185.0 189.0 183.0 183.0 189.0 16:50:27 INFO - PID 1663 | 16:50:27 INFO - PID 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:70.00 stddev:4.84 (6.9%) stddev-sans-first:5.13 16:50:27 INFO - PID 1663 | Values: 70.0 82.0 63.0 70.0 70.0 70.0 66.0 70.0 71.0 69.0 16:50:27 INFO - PID 1663 | -------- Summary: end -------- 16:50:27 INFO - PID 1663 | 16:50:28 INFO - TEST-INFO | 1663: exit 0 16:50:28 INFO - TEST-OK | kraken | took 90263ms 16:50:28 INFO - SUITE-END | took 1233s 16:50:29 INFO - Completed test suite (00:20:33) 16:50:29 INFO - javascript benchmark 16:50:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7859.2272070048475, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3229.370272796805, 3361.1765117546174, 3406.5692409188136, 3595.2270261893696, 3599.15419876329, 5100.617453690974, 5252.921206181905, 5881.617694849842, 6138.662373569452, 6143.600665956709, 6154.569180157391, 6167.444929956303, 6190.226221722285, 6243.594166379182, 6261.996161228409, 5298.841034768963, 5436.374816896068, 5461.2354270402175, 5477.808276710316, 5547.44525547446, 5553.194786755918, 5566.721663916808, 5568.0, 5573.5819813513945, 5579.553635709154, 5389.649672771257, 5463.2078348639625, 5466.34403871534, 5514.0, 5539.695316757597, 5505.642133261365, 5514.6691198527815, 5548.278723765885, 5557.916631250535, 5570.637908535973, 5441.29263195786, 5452.345718513804, 5466.753996070186, 5491.203775452577, 5510.090835012218, 6139.492138333381, 6157.261128664544, 6267.310595834455, 6274.905876411857, 6288.748450061992, 6597.47220222381, 6683.2982536833415, 6697.7655782047395, 6699.330066993261, 6700.162479690039, 6709.832254193635, 6789.898151527731, 6805.523613347068, 6807.149106361705, 6824.317568243186, 6204.782832600928, 6300.0234963580715, 6316.715747791361, 6317.1156038154695, 6319.5576309658345, 6787.0, 6802.727890884309, 6891.586504809728, 6897.448204143657, 6939.618320992394, 6327.841803954938, 6330.841728956813, 6421.5825971311715, 6584.407403333723, 6603.900941485882, 6653.800385988428, 6723.294054124344, 6745.190577130776, 6750.088738020402, 6764.695588698521, 6648.734050638017, 6660.067590537328, 6667.966660166765, 6673.866522669519, 6687.565308254948, 6445.291017988017, 6538.1827271591055, 6557.409833115043, 6633.568818026813, 6670.199576050905, 5383.919241211385, 5385.0, 5386.8383948481605, 5398.595105367034, 5417.133258678593, 4680.95319046812, 4737.123632128068, 4741.193997020515, 4750.809967601326, 4774.355462012584, 6251.28110267316, 6273.874522509615, 6322.652254125976, 6359.586626869239, 6378.617282962944, 5136.255242989746, 5701.372848986608, 6125.693715314216, 6575.539712220098, 6816.07982922309, 6223.222097237845, 6304.936950630435, 6313.463355614732, 6334.0, 6366.522510811615, 4960.851174464772, 5797.072468405119, 5799.420057994167, 5941.821745347646, 6018.187544681499, 6745.156855393076, 6869.210040845367, 6870.831958566956, 6933.514653974174, 6953.860922781617, 6772.356626120708, 6820.727170913108, 6846.246912839683, 6868.416184624363, 6870.793876183722, 5899.646021238739, 6967.233604303624, 7020.122484689414, 7055.329743674342, 7056.223815380406, 6437.645929473924, 6533.281339052605, 6684.63234522106, 6722.226943901318, 6733.663316834237, 6713.160854893138, 6777.661116944231, 6778.389944904982, 6818.249992500921, 6819.147606549182, 6304.401081897213, 6324.051392291193, 6610.504212184009, 6626.337366263335, 6632.767853125118, 6517.478601711757, 6622.63575503352, 6625.17185351831, 6625.4037136657935, 6644.700988455627, 6288.056791481315, 6506.219253689587, 6517.0, 6522.119513865568, 6528.086067950586, 6474.643894585843, 6495.220573531017, 6555.246146693191, 6575.27671956094, 6577.2436169841085, 6435.581687190318, 6449.290578036506, 6451.41937225652, 6469.191351081115, 6471.708773105316], "value": 6103.832193620191, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [758.2190343945736, 772.5673622771249, 820.9917900820992, 1243.4653099167354, 1298.5455090718244, 38753.61246387539, 39931.800340998256, 39939.20121597566, 39968.0, 40136.197276054496, 40074.398884016766, 40327.39508907369, 40369.59630403695, 40369.79815100928, 40520.0, 7707.460477766558, 7855.1752066033105, 8355.206255405727, 8591.44155629882, 8595.484270943733, 8494.193051660082, 8529.10444403338, 8604.311655067611, 8615.095414981432, 8618.87071693922, 8583.313334933191, 8609.139086091403, 8618.353623478233, 8632.913670863305, 8632.913670863305, 8575.614097365633, 8589.656413743443, 8592.8711069334, 8608.225259726592, 8612.48325100496, 8287.378446616527, 8292.751217463485, 8339.374546909006, 8354.749357519284, 8371.539565323905, 37667.434988475194, 37850.43224351637, 37914.43128353077, 37998.0, 38037.23925521474, 3170.429322721909, 3563.572371315433, 3577.83899724513, 3590.6588874056924, 3597.4244120940525, 30483.847580761954, 30754.0, 30764.07707768748, 30794.0, 30796.38407231865, 2948.3513627002026, 2989.222802071477, 2989.760819134464, 3001.669816320203, 3009.6839831817783, 229876.0, 234383.0, 277237.0, 297874.0, 298398.0, 47539.049219015425, 47582.81042973953, 47716.5228347719, 47876.56370308896, 47936.5206347939, 50253.748731256106, 50255.0, 50498.0, 50535.74732126316, 50644.74677626662, 14242.643933901529, 14389.496367627293, 14395.496157634432, 14403.207823569595, 14406.207658578669, 9963.900360996297, 9981.850272245922, 10082.949585252027, 11224.821393753702, 11666.891645293683, 12222.449989750483, 13487.662808429868, 13586.214825960851, 13683.452661893412, 13763.655908602364, 5170.431252562215, 5421.099711654246, 6062.211912451353, 6092.208012958376, 6132.325444201134, 1014.2545229256491, 1027.2706378471369, 1027.6505987964129, 1059.2902755154066, 1062.9202809789297, 23314.7668523316, 24517.0, 24587.893544790528, 24639.260822175005, 24768.752312477005, 1020.5509575786779, 1135.2280449294558, 1135.8353038809325, 1167.2004676796253, 1179.3454632678945, 12425.31660758667, 12566.937165314297, 12593.874061259454, 12662.303573303372, 12671.303078330597, 321519.3924030365, 321907.0, 322433.0, 322608.0, 324979.0, 54308.0, 54562.63593410196, 54630.90738185214, 54641.0, 54708.0, 1382.1430712957829, 1518.8177418709824, 1534.8618624324088, 1567.6472793621344, 1569.591906104421, 23557.175498857458, 23576.0569577215, 23724.762752372255, 23837.523249535257, 23855.403614909766, 37896.242075158894, 37916.62083379241, 37981.62018379781, 38007.80996095113, 38086.80956595199, 269241.0, 269738.0, 270102.0, 270527.0, 270724.0, 32657.020289391356, 34053.65946340604, 34110.0, 34286.48570271398, 34298.657013430544, 43333.0, 45580.0, 45718.0, 45789.31316030196, 45841.77079114583, 28922.42155156927, 30260.394792104475, 30560.541591876583, 31360.843195783877, 31521.369572608877, 24108.87945560261, 31275.53086703747, 31375.05874823759, 31572.684273157894, 31604.525932110577, 34476.0, 36286.09284767902, 37876.81061594785, 37886.052848679006, 37954.0, 25086.62370064487, 30249.160134378395, 31316.843415783685, 31319.0, 31482.685173147976, 23175.76824231736, 24372.390690232885, 24535.50928981375, 24846.627300590866, 25118.497630047663], "value": 17493.008271475, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.30602211185703, 432.7230572433641, 437.95826681547834, 441.82547893582034, 456.9223232050549, 20921.58156836864, 21029.894850525747, 21829.90850457479, 21956.23153189639, 22066.668999964993, 22052.448688782788, 22306.88846555765, 22336.88831555844, 22511.0, 23129.768702313013, 5949.434803693653, 6128.3258841527395, 6161.4146656067605, 6209.813705588839, 6210.0, 5815.970920145393, 6015.639061656293, 6075.63546187228, 6094.603850749687, 6104.816855494342, 5868.853278668046, 5971.07448345505, 5975.0, 6020.969895150518, 6050.788222412217, 5305.0, 5809.6804675742815, 5948.643081415107, 5999.910001349983, 6011.368806275365, 4972.353594032788, 5162.74186290684, 5320.255164277004, 5344.946550534483, 5412.296401467784, 24996.125135620165, 25056.24831255065, 25058.87470562654, 25226.11708590209, 25239.62140567893, 2001.35956493922, 2120.7455105387453, 2210.3589958912057, 2231.152162178378, 2239.193890199543, 17953.0, 17963.012034338106, 18040.097995100194, 18214.27142914282, 18229.7265541017, 1850.6021205440761, 1852.5924296654714, 2138.112683236455, 2146.559955209194, 2156.762756096828, 161859.0, 162665.0, 164123.0, 165270.0, 165347.0, 10625.0, 12710.745785084431, 12758.0, 12839.935800320938, 13017.934910325388, 16170.919145404197, 16308.021518708916, 16466.09436481005, 16507.83492165063, 16551.0, 8863.689770858118, 9021.32340074497, 9057.04900985387, 9145.954270228607, 9182.311326650528, 8657.783555411039, 8686.044535101133, 8710.782230444163, 8838.55807209637, 8871.512066836256, 7071.469639777038, 7503.836905279691, 7808.921910780819, 7916.564588947548, 7938.9206107939335, 2182.90176942038, 2200.1749343996003, 2203.1958335207532, 2204.481946742514, 2397.2328854766306, 932.6010983524712, 962.1869520255373, 975.7024107647094, 986.4820968991337, 1008.4201584089178, 20132.094055767524, 20570.97145142737, 20635.174593016112, 20845.68731469029, 20849.1660333588, 993.9155171810332, 1006.8540061691014, 1023.99488002561, 1026.8716410448694, 1033.9069483746498, 9332.880054393381, 10410.063094321547, 10686.465676716132, 10689.679309620724, 10699.786004280026, 506498.0, 515411.0, 519858.0, 529036.0, 532951.0, 116423.0, 124327.0, 130678.34660826635, 134258.0, 134398.98401523984, 1139.3391832736993, 1174.3306315400312, 1187.8455800745849, 1191.618682021762, 1194.062660811259, 17598.0, 18204.635907282045, 18217.635647287247, 18227.81013322637, 18468.168932397806, 55457.25046708666, 56465.87068258529, 57128.57178570403, 57147.0, 57415.0, 59873.70063149656, 76132.61933690296, 77306.84039739295, 78370.6081469589, 79902.0, 8256.0, 8849.469031857852, 8938.150875667061, 9270.397424167408, 9314.534273286174, 13589.592312230649, 14645.92677036608, 14716.484923027643, 14754.557363279118, 15012.249387530797, 12264.509419623115, 12323.753524929272, 12415.689607759878, 12481.0, 12519.248845069325, 9645.276604254568, 11191.32852028881, 11522.596709115141, 11590.88409115898, 11892.464839082435, 12557.93721031389, 12629.216844939932, 12638.747225055631, 12680.556180533637, 12698.682532936751, 7775.416843736629, 8784.715973846718, 8839.0, 8881.644734210819, 9211.723648290563, 10511.421871797125, 12082.516699331929, 12164.2701437914, 12404.19372740769, 12448.0], "value": 10379.797771021878, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1415.4267521653728, 1466.5380405172364, 1477.0399240493678, 1487.9925600371996, 1492.306232426196, 11417.600383986552, 11583.942080289587, 11872.94063529681, 11904.107191960606, 11997.940010299959, 11667.299962002286, 11843.407829608528, 11852.703682407944, 11867.465964031619, 11886.821697674543, 2506.285708573057, 2562.128876182098, 2563.218218443374, 2575.9227223183334, 2577.5875859862426, 2509.2095989763257, 2568.7174410814796, 2572.8070394720376, 2575.562154433751, 2579.651747014148, 2520.4959008198343, 2535.353484861367, 2539.11131104113, 2555.501677172943, 2557.539642864284, 2017.5258814178655, 2473.4682043360763, 2493.2021753039035, 2495.438526331579, 2496.9750302496923, 2102.0, 2443.4135807406274, 2497.3382053755586, 2527.0776166699025, 2531.2153232497985, 11500.367479788609, 11531.250468719507, 11644.534218631245, 11646.941765291203, 11661.425707529459, 6370.203724534434, 6529.673516324165, 6618.4705223582, 6619.735210591571, 6631.436327912085, 3250.6099268087983, 4213.262679031188, 4278.8716338509585, 4286.87139385819, 4298.054428025829, 1123.9269447485888, 1160.1994623709613, 1217.385220463669, 1274.241826113461, 1282.8011658192995, 15124.54626361211, 15441.15073670959, 16160.27278772458, 16181.676366472604, 16262.349506019746, 5355.678659280456, 5418.458154184629, 5568.359638641527, 5607.887842243133, 5654.321481422256, 11542.249753765978, 11588.304701717923, 11594.826077608674, 11626.941865290619, 11633.825492617618, 6450.8064758057335, 6484.254310754323, 6485.059666348353, 6489.091527186198, 6515.7067931943175, 5644.604877658607, 6101.908471372933, 6191.1641928339095, 6219.689015549205, 6275.341089185661, 4308.827646894089, 4376.584224498668, 4397.31841564558, 4402.119576084796, 4435.489918659331, 1267.2269915351628, 1271.453275091701, 1272.63729836996, 1283.076185146693, 1285.8264134341932, 788.1960400391683, 796.6056801883105, 822.3256929317902, 823.9464434811719, 846.9449485783405, 6056.727447264883, 6112.388761123852, 6247.75008999644, 6372.522060845455, 6484.703059388047, 605.9606125601822, 609.740860134441, 615.2248167309223, 615.2432508015077, 622.4211483320555, 3473.3921563726753, 3644.034330902313, 3885.8445662173763, 3933.645971862546, 3983.5418926823786, 10508.579656813641, 14358.641033974234, 14390.784138237936, 14558.563243102724, 14651.487197948021, 14200.0769949955, 14530.636734081521, 14531.92734036323, 14539.709205815825, 14587.708245835236, 8299.170082991652, 8440.662373505114, 8488.83022339562, 8564.458397488512, 8565.314774818, 6362.968185159045, 6406.455451286693, 6695.799126026227, 7100.325469080429, 7169.7132114716915, 12520.49918003306, 12565.87434125647, 12925.676858078623, 13017.21896686202, 13146.934265328995, 13276.070675052653, 13336.0, 13484.59546213576, 13572.253526055767, 13733.45066197341, 9108.863367049633, 9249.722508324761, 9272.979972202917, 9311.208547273558, 9316.6273349068, 10356.0, 10593.576256949944, 10663.89336106629, 10670.039696427359, 10726.51730672106, 9121.859767529058, 9126.224270936778, 9177.54112294396, 9178.908210917805, 9289.349745518019, 8814.250788683034, 8892.822143557221, 8910.509921954355, 8977.640894364153, 9013.96339420975, 7117.501774875709, 7396.890466430079, 8089.0, 8136.593170341577, 8254.917450825655, 6008.429199226067, 6122.663253521099, 6144.8771024580155, 6172.907406388818, 6191.318954914866, 6057.697595016916, 6106.603070800384, 6156.538259630456, 6168.321484636776, 6184.319724830175], "value": 5260.565453365996, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.27364134909504, 381.3383779143186, 399.73217943977534, 401.27369461275094, 401.63651895035, 6019.398060193978, 6182.8252631999885, 6317.873642527146, 6366.395192456721, 6371.745130194799, 4981.427135879375, 6170.259568851744, 6255.374462553742, 6291.748330066792, 6304.968475157618, 4301.720388174775, 4487.663425243103, 4613.723176609397, 4615.861524154281, 4622.815087396509, 4021.678265738734, 4576.1762882681105, 4583.702059366147, 4596.965682721378, 4600.263957766758, 3523.3834079036064, 4478.104379124172, 4549.0, 4567.584048944837, 4619.74591397473, 3550.804705741183, 3648.288583726161, 4044.47421835161, 4479.3057076153245, 4524.5701658342405, 3250.7074363307415, 3849.711271654637, 3865.4781604483314, 3868.2843673920415, 3879.8254078566533, 6103.786367477162, 6158.476529494998, 6175.505959523227, 6201.782937597162, 6202.875942481125, 4553.544645535453, 4871.4195066626, 4949.529794669501, 5305.44292849253, 5474.835754927359, 6750.1562304711915, 6759.155105611799, 6778.830529236809, 6779.423748981342, 6798.912174052128, 3725.4411838224214, 4342.30523116303, 4441.444819397575, 4444.5555194561575, 4453.198424283628, 5943.9598070337515, 6134.846628834225, 6449.9035164022225, 7102.893456598155, 7143.642817859139, 3834.559025712079, 4308.978455107705, 4462.776861156929, 4495.685302028826, 4504.909901801946, 3694.5751238607368, 4185.100203456272, 4266.338717498793, 4593.196190666687, 4601.309803529497, 5377.838664840061, 5445.891082178334, 5450.5912056595125, 5476.67139971603, 5490.368607610176, 5160.354955630546, 6388.105665206875, 6782.050512928194, 6884.690188941409, 6891.310868913169, 1319.4062671797728, 1545.3123360104871, 1558.2520390212762, 1558.9844101559065, 1561.6017915431719, 969.8787651543557, 999.8601594182637, 1005.4218824176128, 1015.8679371681634, 1032.3650954662978, 5004.2493625956395, 6367.968160159169, 6373.71318290678, 6459.580127291711, 6465.612063276219, 6175.59858609189, 6194.938050619527, 6714.529982901247, 6784.0, 7054.08296921397, 6330.341256672116, 6393.817143828313, 6434.747838256572, 6490.740370385131, 6502.219733631994, 3222.2749881276995, 3423.7089847363254, 3596.011096948308, 3626.3472574936764, 3687.7277339317893, 5650.604457688005, 6331.733653269364, 7246.0, 7251.601161936039, 7282.490225684257, 7159.463040272, 7223.349898509159, 7241.855162896818, 7288.489805713549, 7317.658349328271, 5560.777568897199, 6023.819285421444, 6134.5092392608485, 7204.0, 7204.4956853019785, 5637.774489020393, 6296.166724973688, 6532.085508028785, 6754.696038678173, 7013.333733295329, 6721.395074443323, 6883.141551781091, 6886.965565172142, 6997.0, 7019.19279282889, 6466.579672321284, 6735.865282694416, 6756.290589488033, 7054.929450705633, 7357.668904899186, 3797.848086076526, 3832.9616703832603, 4526.20791361517, 4553.2259515881715, 4558.635309175324, 5831.891940531285, 6300.637261027115, 6869.313068693091, 7128.180259270043, 7183.604901730246, 5916.408359164049, 6708.932910670831, 6910.7581234656545, 6911.688973996082, 6943.236244013256, 6801.795946121624, 6812.96593517049, 6944.375006249462, 6947.0, 6958.269381714847, 6199.443409094222, 6199.5040396767245, 6426.839329016625, 6762.951742479923, 6863.1517920062615, 5097.235414687826, 6080.452759251689, 6611.702473388613, 7010.773114704804, 7024.262452442625, 4975.402951645826, 6750.0, 6751.156105486814, 6752.966235168793, 6771.559848609824], "value": 4802.600724932891, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [604.9334573196945, 614.9262088549375, 676.41828027896, 715.2418436457356, 849.6304107713144, 16071.59821004474, 16495.010299382044, 16505.83494165058, 18053.90973045133, 19291.324803631876, 17740.0, 18048.54878628035, 18248.361307354244, 18322.72515912259, 18625.975571343606, 1002.3685078400615, 1033.736397218712, 1034.0693375961657, 1036.1037702387418, 1040.7242080848582, 990.5988074829705, 1018.8777346718405, 1038.833786594145, 1045.132539991805, 1046.4244665434019, 1074.414444127952, 1078.3422112511362, 1078.4607696151925, 1082.155918383662, 1082.3289560472479, 1069.7806949575318, 1073.98508409553, 1075.3547871277249, 1076.2358725305044, 1076.445630500293, 782.1708988472237, 803.0203152154421, 803.1727320859524, 803.2409373142383, 804.0512195609238, 16904.323827046905, 17285.0, 17489.7376539352, 17539.73690394645, 17869.46391608254, 17093.0, 17219.139043047922, 17319.047452390114, 17378.3048678054, 17514.38699645506, 14042.508512202025, 14325.928370358186, 14360.712785744332, 14510.927445362706, 14536.127832330094, 3618.873339433107, 3622.8913132606062, 3648.0332711831384, 3649.635036496329, 3654.086478380405, 125060.0, 126113.73886261112, 127702.3614881929, 127711.0, 128736.35631821875, 13373.398197081155, 13378.732425351438, 13410.530631428048, 13509.527166549124, 13528.0, 21505.462363441038, 25340.493190136094, 25421.745782542308, 25652.74347256541, 25720.0, 7611.1627720950655, 7916.84166316682, 7993.480423772437, 8003.8799418008775, 8007.559584222924, 3950.545687245946, 3987.5813039631003, 3998.0804414984136, 4023.1551374211745, 4035.9394609080887, 4340.804663790137, 4359.607635312837, 4449.822007119744, 4450.955490445054, 4458.5318541553315, 869.5956380283199, 895.9417637853649, 898.9460632362079, 900.1493588558749, 903.1555495611624, 538.8625900395374, 657.3525077798365, 659.0740010285557, 673.3804899492478, 688.8243497908002, 10928.398938058483, 11523.366214858095, 11919.0, 11982.0, 12194.57318993831, 649.2630863969429, 651.9087327774115, 655.9603029198678, 658.3021996683531, 674.7604600366898, 4916.582090522345, 5318.468153184651, 5343.064963631349, 5542.695151766611, 5608.49523542875, 110034.44982775034, 111848.44075779569, 112463.0, 113344.0, 113576.86423135662, 83157.50527484185, 94743.0, 95979.0, 98014.0, 98366.0, 63971.360286397474, 77123.0, 81387.3722525558, 81586.59206704047, 82846.58576707078, 11003.559857605605, 12048.554173499237, 12686.746265074462, 12732.172408793398, 12770.553030643883, 28205.858970705016, 30207.942722004624, 33136.33727325488, 37768.05579860525, 39864.60135398609, 62493.0, 68572.0, 68612.656936715, 69583.30416695768, 69680.0, 16328.836711632732, 16397.180140992663, 16435.342586296414, 16443.260053297392, 16470.588235294214, 17311.3941012064, 17559.736603951205, 17733.113344332472, 17988.460346189637, 18300.450986470427, 16017.439389621308, 16230.67538649244, 16414.917925410296, 16645.50063498097, 16679.66640667204, 13984.440622375398, 15844.841551584337, 16478.34086636521, 16485.670286593962, 16554.337826486804, 15436.151011694465, 15655.295511702228, 16127.0, 16163.919180404493, 16191.433299834449, 11233.775324493301, 11467.770644587228, 11643.76712465729, 12295.877041229473, 12338.3830808461, 12223.388830558615, 12282.078844086907, 13082.018848586204, 13654.590362289147, 13667.31663416845], "value": 8415.565962059813, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1532.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 116.0, 114.0, 114.0, 113.0, 116.0, 130.0, 152.0, 114.0, 114.0], "value": 118.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 110.0, 109.0, 169.0, 144.0, 144.0, 92.0, 94.0, 129.0, 135.0], "value": 126.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 198.0, 195.0, 211.0, 220.0, 228.0, 205.0, 273.0, 239.0, 207.0], "value": 217.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 55.0, 56.0, 84.0, 57.0, 65.0, 85.0, 74.0, 80.0, 63.0], "value": 70.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 68.0, 95.0, 104.0, 79.0, 71.0, 85.0, 70.0, 77.0, 116.0], "value": 85.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 88.0, 89.0, 90.0, 88.0, 89.0, 78.0, 130.0, 134.0], "value": 96.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 150.0, 98.0, 97.0, 96.0, 96.0, 96.0, 96.0], "value": 101.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 83.0, 136.0, 107.0, 142.0, 81.0, 144.0, 77.0, 133.0, 136.0], "value": 115.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 51.0, 49.0, 51.0, 50.0, 51.0, 52.0, 51.0, 52.0], "value": 50.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 65.0, 70.0, 81.0, 65.0, 58.0, 56.0, 55.0, 55.0, 59.0], "value": 61.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 88.0, 87.0, 88.0, 103.0, 109.0, 85.0, 126.0, 88.0], "value": 94.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 138.0, 137.0, 136.0, 135.0, 136.0, 144.0, 140.0, 148.0, 149.0], "value": 140.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 185.0, 181.0, 184.0, 186.0, 185.0, 189.0, 183.0, 183.0, 189.0], "value": 183.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 82.0, 63.0, 70.0, 70.0, 70.0, 66.0, 70.0, 71.0, 69.0], "value": 70.1, "unit": "score"}]}]} 16:50:29 INFO - Return code: 0 16:50:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:50:29 INFO - # TBPL SUCCESS # 16:50:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:50:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:50:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:50:29 INFO - 2017-05-23 16:50:29.166 screenresolution[1670:11027] starting screenresolution argv=screenresolution get 16:50:29 INFO - 2017-05-23 16:50:29.169 screenresolution[1670:11027] Display 0: 1600x1200x32@60 16:50:29 INFO - 2017-05-23 16:50:29.175 screenresolution[1671:11030] starting screenresolution argv=screenresolution list 16:50:29 INFO - Available Modes on Display 0 16:50:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:50:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:50:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:50:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:50:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:50:29 INFO - Intel Iris: 16:50:29 INFO - Chipset Model: Intel Iris 16:50:29 INFO - Type: GPU 16:50:29 INFO - Bus: Built-In 16:50:29 INFO - VRAM (Dynamic, Max): 1536 MB 16:50:29 INFO - Vendor: Intel (0x8086) 16:50:29 INFO - Device ID: 0x0a2e 16:50:29 INFO - Revision ID: 0x0009 16:50:29 INFO - Displays: 16:50:29 INFO - Display: 16:50:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:50:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:50:29 INFO - Display Serial Number: 1600x1200 60 16:50:29 INFO - Main Display: Yes 16:50:29 INFO - Mirror: Off 16:50:29 INFO - Online: Yes 16:50:29 INFO - Rotation: Supported 16:50:29 INFO - Return code: 0 16:50:29 INFO - Running post-action listener: _resource_record_post_action 16:50:29 INFO - [mozharness: 2017-05-23 23:50:29.411132Z] Finished run-tests step (success) 16:50:29 INFO - Running post-run listener: _resource_record_post_run 16:50:29 INFO - Total resource usage - Wall time: 1255s; CPU: 54.0%; Read bytes: 40007168; Write bytes: 567993344; Read time: 290; Write time: 1791 16:50:29 INFO - TinderboxPrint: CPU usage
53.6% 16:50:29 INFO - TinderboxPrint: I/O read bytes / time
40,007,168 / 290 16:50:29 INFO - TinderboxPrint: I/O write bytes / time
567,993,344 / 1,791 16:50:29 INFO - TinderboxPrint: CPU idle
2,331.7 (46.4%) 16:50:29 INFO - TinderboxPrint: CPU system
1,020.4 (20.3%) 16:50:29 INFO - TinderboxPrint: CPU user
1,669.6 (33.2%) 16:50:29 INFO - TinderboxPrint: Swap in / out
284,413,952 / 0 16:50:29 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 236569088; Write bytes: 229738496; Read time: 17049; Write time: 493 16:50:29 INFO - run-tests - Wall time: 1235s; CPU: 54.0%; Read bytes: 37930496; Write bytes: 318954496; Read time: 270; Write time: 1247 16:50:29 INFO - Running post-run listener: _upload_blobber_files 16:50:29 INFO - Blob upload gear active. 16:50:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:50:29 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 16:50:29 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'] 16:50:29 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 16:50:30 INFO - (blobuploader) - INFO - Open directory for files ... 16:50:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:50:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:50:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:50:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:50:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:50:31 INFO - (blobuploader) - INFO - Done attempting. 16:50:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:50:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:50:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:50:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:50:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:50:32 INFO - (blobuploader) - INFO - Done attempting. 16:50:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:50:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:50:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:50:33 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:50:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:50:33 INFO - (blobuploader) - INFO - Done attempting. 16:50:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:50:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:50:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:50:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:50:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:50:35 INFO - (blobuploader) - INFO - Done attempting. 16:50:35 INFO - (blobuploader) - INFO - Iteration through files over. 16:50:35 INFO - Return code: 0 16:50:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:50:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:50:35 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6243a3a62294db1023e2fad651b9cac16e74dbf138a0030e41afe1d0c578841cfb7b9006f9e9db31d3365dc17e9c91ff0bea31e3994d4005dfabed33518623a2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ceb7f69c2ec803a5718e4d4703841876030ce7fc449f50610707f08f498783e8af38e44eb9fc40b77ea2c913103113987ac1879f65d334aea18d2d59f7cc8f9c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5185a14ab3f4a8ae4b0dcdf9241468049c9f58d8e8a4c68ef35c65ab948b8f189c6b02a9a2856a958bd212cd84ed25813fa144300b0a3e89357b136360586b3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6db999553d1c343529c765908bb8dd0ffafbfa59f7e2a9dddbceff14350aeaf302beecf2f834caf84e82b8463bc616d1503fa461640d1528eb2975dfce34a95e"} 16:50:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:50:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:50:35 INFO - Contents: 16:50:35 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6243a3a62294db1023e2fad651b9cac16e74dbf138a0030e41afe1d0c578841cfb7b9006f9e9db31d3365dc17e9c91ff0bea31e3994d4005dfabed33518623a2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ceb7f69c2ec803a5718e4d4703841876030ce7fc449f50610707f08f498783e8af38e44eb9fc40b77ea2c913103113987ac1879f65d334aea18d2d59f7cc8f9c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5185a14ab3f4a8ae4b0dcdf9241468049c9f58d8e8a4c68ef35c65ab948b8f189c6b02a9a2856a958bd212cd84ed25813fa144300b0a3e89357b136360586b3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6db999553d1c343529c765908bb8dd0ffafbfa59f7e2a9dddbceff14350aeaf302beecf2f834caf84e82b8463bc616d1503fa461640d1528eb2975dfce34a95e"} 16:50:35 INFO - Running post-run listener: copy_logs_to_upload_dir 16:50:35 INFO - Copying logs to upload dir... 16:50:35 INFO - mkdir: /builds/slave/test/build/upload/logs 16:50:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1337.657817 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-05-23 16:50:35.448074) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-23 16:50:35.451322) ========= 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.AMrr84TUEh/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.0rg2ptp0BZ/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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/6243a3a62294db1023e2fad651b9cac16e74dbf138a0030e41afe1d0c578841cfb7b9006f9e9db31d3365dc17e9c91ff0bea31e3994d4005dfabed33518623a2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ceb7f69c2ec803a5718e4d4703841876030ce7fc449f50610707f08f498783e8af38e44eb9fc40b77ea2c913103113987ac1879f65d334aea18d2d59f7cc8f9c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5185a14ab3f4a8ae4b0dcdf9241468049c9f58d8e8a4c68ef35c65ab948b8f189c6b02a9a2856a958bd212cd84ed25813fa144300b0a3e89357b136360586b3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6db999553d1c343529c765908bb8dd0ffafbfa59f7e2a9dddbceff14350aeaf302beecf2f834caf84e82b8463bc616d1503fa461640d1528eb2975dfce34a95e"} build_url:https://queue.taskcluster.net/v1/task/STUL3AeuQ4GIFvud50BZtQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011101 build_url: 'https://queue.taskcluster.net/v1/task/STUL3AeuQ4GIFvud50BZtQ/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/6243a3a62294db1023e2fad651b9cac16e74dbf138a0030e41afe1d0c578841cfb7b9006f9e9db31d3365dc17e9c91ff0bea31e3994d4005dfabed33518623a2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ceb7f69c2ec803a5718e4d4703841876030ce7fc449f50610707f08f498783e8af38e44eb9fc40b77ea2c913103113987ac1879f65d334aea18d2d59f7cc8f9c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5185a14ab3f4a8ae4b0dcdf9241468049c9f58d8e8a4c68ef35c65ab948b8f189c6b02a9a2856a958bd212cd84ed25813fa144300b0a3e89357b136360586b3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6db999553d1c343529c765908bb8dd0ffafbfa59f7e2a9dddbceff14350aeaf302beecf2f834caf84e82b8463bc616d1503fa461640d1528eb2975dfce34a95e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-23 16:50:35.483894) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 16:50:35.484238) ========= 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.AMrr84TUEh/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.0rg2ptp0BZ/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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.004503 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 16:50:35.532896) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-23 16:50:35.533247) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-23 16:50:35.533595) ========= ========= Total master_lag: 0.19 =========