builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0163 starttime: 1496180696.28 results: success (0) revision: 692e277e2b9f3bb36d6d67e1166d135228f2d851 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 14:44:56.281039) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 14:44:56.281565) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 14:44:56.281854) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 14:44:56.410089) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 14:44:56.410520) ========= 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.vGdJP8YZLi/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.1S6GguDfGB/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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-30 14:44:56-- 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% 13.8M=0.001s 2017-05-30 14:44:56 (13.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.227384 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 14:44:56.661687) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 14:44:56.662305) ========= 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.vGdJP8YZLi/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.1S6GguDfGB/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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.036246 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 14:44:56.720735) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-30 14:44:56.721216) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 692e277e2b9f3bb36d6d67e1166d135228f2d851 --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 692e277e2b9f3bb36d6d67e1166d135228f2d851 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vGdJP8YZLi/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.1S6GguDfGB/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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-30 14:44:56,793 truncating revision to first 12 chars 2017-05-30 14:44:56,793 Setting DEBUG logging. 2017-05-30 14:44:56,793 attempt 1/10 2017-05-30 14:44:56,794 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/692e277e2b9f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-30 14:44:58,089 unpacking tar archive at: mozilla-central-692e277e2b9f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.575101 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-30 14:44:58.319880) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 14:44:58.320362) ========= script_repo_revision: 692e277e2b9f3bb36d6d67e1166d135228f2d851 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 14:44:58.320913) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 14:44:58.321354) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 14:44:58.340987) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-05-30 14:44:58.341407) ========= /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.vGdJP8YZLi/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.1S6GguDfGB/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 14:44:58 INFO - MultiFileLogger online at 20170530 14:44:58 in /builds/slave/test 14:44:58 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 14:44:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:44:58 INFO - {'append_to_log': False, 14:44:58 INFO - 'base_work_dir': '/builds/slave/test', 14:44:58 INFO - 'blob_upload_branch': 'Firefox', 14:44:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:44:58 INFO - 'branch': 'Firefox', 14:44:58 INFO - 'buildbot_json_path': 'buildprops.json', 14:44:58 INFO - 'config_files': ('talos/mac_config.py',), 14:44:58 INFO - 'default_actions': ('clobber', 14:44:58 INFO - 'read-buildbot-config', 14:44:58 INFO - 'download-and-extract', 14:44:58 INFO - 'populate-webroot', 14:44:58 INFO - 'create-virtualenv', 14:44:58 INFO - 'install', 14:44:58 INFO - 'setup-mitmproxy', 14:44:58 INFO - 'run-tests'), 14:44:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:44:58 INFO - 'download_minidump_stackwalk': True, 14:44:58 INFO - 'download_symbols': 'ondemand', 14:44:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:44:58 INFO - 'tooltool.py': '/tools/tooltool.py', 14:44:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:44:58 INFO - '/tools/misc-python/virtualenv.py')}, 14:44:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:44:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:44:58 INFO - 'gecko_profile': False, 14:44:58 INFO - 'gecko_profile_interval': 0, 14:44:58 INFO - 'installer_path': 'installer.exe', 14:44:58 INFO - 'log_level': 'info', 14:44:58 INFO - 'log_name': 'talos', 14:44:58 INFO - 'log_to_console': True, 14:44:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:44:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:44:58 INFO - 'opt_config_files': (), 14:44:58 INFO - 'pip_index': False, 14:44:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:44:58 INFO - 'cmd': ('bash', 14:44:58 INFO - '-c', 14:44:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:44:58 INFO - 'enabled': True, 14:44:58 INFO - 'halt_on_failure': False, 14:44:58 INFO - 'name': 'check_screen_resolution'},), 14:44:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:44:58 INFO - 'cmd': ('bash', 14:44:58 INFO - '-c', 14:44:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:44:58 INFO - 'enabled': True, 14:44:58 INFO - 'halt_on_failure': False, 14:44:58 INFO - 'name': 'check_screen_resolution'},), 14:44:58 INFO - 'run_cmd_checks_enabled': True, 14:44:58 INFO - 'suite': 'dromaeojs-e10s', 14:44:58 INFO - 'system_bits': '32', 14:44:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:44:58 INFO - 'title': 't-yosemite-r7-0163', 14:44:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:44:58 INFO - 'use_talos_json': True, 14:44:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:44:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:44:58 INFO - 'work_dir': 'build'} 14:44:58 INFO - [mozharness: 2017-05-30 21:44:58.529608Z] Running clobber step. 14:44:58 INFO - Running pre-action listener: _resource_record_pre_action 14:44:58 INFO - Running main action method: clobber 14:44:58 INFO - rmtree: /builds/slave/test/build 14:44:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:45:01 INFO - Running post-action listener: _resource_record_post_action 14:45:01 INFO - [mozharness: 2017-05-30 21:45:01.175646Z] Finished clobber step (success) 14:45:01 INFO - [mozharness: 2017-05-30 21:45:01.175772Z] Running read-buildbot-config step. 14:45:01 INFO - Running pre-action listener: _resource_record_pre_action 14:45:01 INFO - Running main action method: read_buildbot_config 14:45:01 INFO - Using buildbot properties: 14:45:01 INFO - { 14:45:01 INFO - "project": "", 14:45:01 INFO - "product": "firefox", 14:45:01 INFO - "who": "ryanvm@gmail.com", 14:45:01 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 14:45:01 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 14:45:01 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 14:45:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 14:45:01 INFO - "stage_platform": "macosx64", 14:45:01 INFO - "basedir": "/builds/slave/test", 14:45:01 INFO - "buildnumber": 81, 14:45:01 INFO - "platform": "yosemite_r7", 14:45:01 INFO - "parent_task_id": "GUpuU_giRxaWAyN0ULUEyQ", 14:45:01 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 14:45:01 INFO - "slavebuilddir": "test", 14:45:01 INFO - "taskId": "benrIQFOTdebt1O7qv1MlA", 14:45:01 INFO - "branch": "mozilla-central", 14:45:01 INFO - "script_repo_revision": "production", 14:45:01 INFO - "revision": "692e277e2b9f3bb36d6d67e1166d135228f2d851", 14:45:01 INFO - "slavename": "t-yosemite-r7-0163", 14:45:01 INFO - "repo_path": "mozilla-central" 14:45:01 INFO - } 14:45:01 INFO - Finding installer, test and symbols from parent task. 14:45:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/benrIQFOTdebt1O7qv1MlA'}, attempt #1 14:45:02 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/benrIQFOTdebt1O7qv1MlA'}, attempt #1 14:45:02 INFO - Task dependencies: Z945v0dqQTepjpAa-WmTmA GUpuU_giRxaWAyN0ULUEyQ 14:45:02 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GUpuU_giRxaWAyN0ULUEyQ'}, attempt #1 14:45:02 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/GUpuU_giRxaWAyN0ULUEyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:45:02 INFO - Set test_url: https://queue.taskcluster.net/v1/task/GUpuU_giRxaWAyN0ULUEyQ/artifacts/public/build/test_packages.json 14:45:02 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/GUpuU_giRxaWAyN0ULUEyQ/artifacts/public/build/target.crashreporter-symbols.zip 14:45:02 INFO - Running post-action listener: _resource_record_post_action 14:45:02 INFO - [mozharness: 2017-05-30 21:45:02.979590Z] Finished read-buildbot-config step (success) 14:45:02 INFO - [mozharness: 2017-05-30 21:45:02.979712Z] Running download-and-extract step. 14:45:02 INFO - Running pre-action listener: _resource_record_pre_action 14:45:02 INFO - Running main action method: download_and_extract 14:45:02 INFO - mkdir: /builds/slave/test/build/tests 14:45:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:45:02 INFO - trying https://queue.taskcluster.net/v1/task/GUpuU_giRxaWAyN0ULUEyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 14:45:02 INFO - Downloading https://queue.taskcluster.net/v1/task/GUpuU_giRxaWAyN0ULUEyQ/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 14:45:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GUpuU_giRxaWAyN0ULUEyQ/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 14:45:03 INFO - Downloaded 1325 bytes. 14:45:03 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:45:03 INFO - Using the following test package requirements: 14:45:03 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:45:03 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 14:45:03 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:45:03 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:45:03 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 14:45:03 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:45:03 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 14:45:03 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:45:03 INFO - u'jsshell-mac.zip'], 14:45:03 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:45:03 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 14:45:03 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:45:03 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:45:03 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 14:45:03 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:45:03 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 14:45:03 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:45:03 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 14:45:03 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:45:03 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 14:45:03 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:45:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GUpuU_giRxaWAyN0ULUEyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:45:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GUpuU_giRxaWAyN0ULUEyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:45:03 INFO - Fetch https://queue.taskcluster.net/v1/task/GUpuU_giRxaWAyN0ULUEyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:45:05 INFO - Content-Length response header: 57631194 14:45:05 INFO - Bytes received: 57631194 14:45:23 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 14:45:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GUpuU_giRxaWAyN0ULUEyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:45:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GUpuU_giRxaWAyN0ULUEyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:45:23 INFO - Fetch https://queue.taskcluster.net/v1/task/GUpuU_giRxaWAyN0ULUEyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:45:25 INFO - Content-Length response header: 57631194 14:45:25 INFO - Bytes received: 57631194 14:45:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GUpuU_giRxaWAyN0ULUEyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 14:45:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GUpuU_giRxaWAyN0ULUEyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:45:43 INFO - Fetch https://queue.taskcluster.net/v1/task/GUpuU_giRxaWAyN0ULUEyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 14:45:44 INFO - Content-Length response header: 14447425 14:45:44 INFO - Bytes received: 14447425 14:45:44 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')]} 14:45:44 INFO - trying https://queue.taskcluster.net/v1/task/GUpuU_giRxaWAyN0ULUEyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:45:44 INFO - Downloading https://queue.taskcluster.net/v1/task/GUpuU_giRxaWAyN0ULUEyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 14:45:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GUpuU_giRxaWAyN0ULUEyQ/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 14:45:46 INFO - Downloaded 67907215 bytes. 14:45:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GUpuU_giRxaWAyN0ULUEyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:45:46 INFO - mkdir: /builds/slave/test/properties 14:45:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:45:46 INFO - Writing to file /builds/slave/test/properties/build_url 14:45:46 INFO - Contents: 14:45:46 INFO - build_url:https://queue.taskcluster.net/v1/task/GUpuU_giRxaWAyN0ULUEyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:45:46 INFO - Running post-action listener: _resource_record_post_action 14:45:46 INFO - Running post-action listener: set_extra_try_arguments 14:45:46 INFO - [mozharness: 2017-05-30 21:45:46.373354Z] Finished download-and-extract step (success) 14:45:46 INFO - [mozharness: 2017-05-30 21:45:46.373496Z] Running populate-webroot step. 14:45:46 INFO - Running pre-action listener: _resource_record_pre_action 14:45:46 INFO - Running main action method: populate_webroot 14:45:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:45:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:45:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:45:46 INFO - '--tppagecycles', 14:45:46 INFO - '7'], 14:45:46 INFO - 'tests': ['tsvgm']}}, 14:45:46 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:45:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:45:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:45:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:45:46 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:45:46 INFO - 'talos_options': ['--disable-e10s'], 14:45:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:45:46 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:45:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:45:46 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:45:46 INFO - 'talos_options': ['--disable-e10s'], 14:45:46 INFO - 'tests': ['damp', 'tps']}, 14:45:46 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:45:46 INFO - 'tests': ['damp', 'tps']}, 14:45:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:45:46 INFO - 'tests': ['dromaeo_dom']}, 14:45:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:45:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:45:46 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 14:45:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 14:45:46 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 14:45:46 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 14:45:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:45:46 INFO - 'tests': ['a11yr', 14:45:46 INFO - 'ts_paint', 14:45:46 INFO - 'tpaint', 14:45:46 INFO - 'sessionrestore', 14:45:46 INFO - 'sessionrestore_no_auto_restore', 14:45:46 INFO - 'tabpaint']}, 14:45:46 INFO - 'other-e10s': {'tests': ['a11yr', 14:45:46 INFO - 'ts_paint', 14:45:46 INFO - 'tpaint', 14:45:46 INFO - 'sessionrestore', 14:45:46 INFO - 'sessionrestore_no_auto_restore', 14:45:46 INFO - 'tabpaint']}, 14:45:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:45:46 INFO - 'ts_paint', 14:45:46 INFO - 'tpaint', 14:45:46 INFO - 'sessionrestore', 14:45:46 INFO - 'sessionrestore_no_auto_restore', 14:45:46 INFO - 'tabpaint']}, 14:45:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:45:46 INFO - 'ts_paint', 14:45:46 INFO - 'tpaint', 14:45:46 INFO - 'sessionrestore', 14:45:46 INFO - 'sessionrestore_no_auto_restore', 14:45:46 INFO - 'tabpaint']}, 14:45:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:45:46 INFO - 'tests': ['a11yr', 14:45:46 INFO - 'ts_paint', 14:45:46 INFO - 'tpaint', 14:45:46 INFO - 'sessionrestore', 14:45:46 INFO - 'sessionrestore_no_auto_restore', 14:45:46 INFO - 'tabpaint']}, 14:45:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:45:46 INFO - 'tests': ['a11yr', 14:45:46 INFO - 'ts_paint', 14:45:46 INFO - 'tpaint', 14:45:46 INFO - 'sessionrestore', 14:45:46 INFO - 'sessionrestore_no_auto_restore', 14:45:46 INFO - 'tabpaint']}, 14:45:46 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 14:45:46 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 14:45:46 INFO - 'bloom_basic_ref']}, 14:45:46 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 14:45:46 INFO - 'talos_options': ['--mitmproxy', 14:45:46 INFO - 'mitmproxy-recording-1.mp', 14:45:46 INFO - '--firstNonBlankPaint'], 14:45:46 INFO - 'tests': ['Quantum_1']}, 14:45:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:45:46 INFO - 'tests': ['tsvgx', 14:45:46 INFO - 'tsvgr_opacity', 14:45:46 INFO - 'tart', 14:45:46 INFO - 'tscrollx', 14:45:46 INFO - 'cart', 14:45:46 INFO - 'tsvg_static']}, 14:45:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:45:46 INFO - 'tsvgr_opacity', 14:45:46 INFO - 'tart', 14:45:46 INFO - 'tscrollx', 14:45:46 INFO - 'cart', 14:45:46 INFO - 'tsvg_static']}, 14:45:46 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:45:46 INFO - 'talos_options': ['--disable-e10s'], 14:45:46 INFO - 'tests': ['tp5o']}, 14:45:46 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:45:46 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:45:46 INFO - 'talos_options': ['--disable-e10s', 14:45:46 INFO - '--xperf_path', 14:45:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:45:46 INFO - 'tests': ['tp5n']}, 14:45:46 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:45:46 INFO - 'talos_options': ['--xperf_path', 14:45:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:45:46 INFO - 'tests': ['tp5n']}}, 14:45:46 INFO - 'talos.zip': {'path': '', 14:45:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:45:46 INFO - Running post-action listener: _resource_record_post_action 14:45:46 INFO - [mozharness: 2017-05-30 21:45:46.395542Z] Finished populate-webroot step (success) 14:45:46 INFO - [mozharness: 2017-05-30 21:45:46.395702Z] Running create-virtualenv step. 14:45:46 INFO - Running pre-action listener: _resource_record_pre_action 14:45:46 INFO - Running main action method: create_virtualenv 14:45:46 INFO - Creating virtualenv /builds/slave/test/build/venv 14:45:46 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:45:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:45:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:45:46 INFO - Using real prefix '/tools/python27' 14:45:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:45:47 INFO - Installing distribute.............................................................................................................................................................................................done. 14:45:50 INFO - Installing pip.................done. 14:45:50 INFO - Return code: 0 14:45:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:45:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:45:50 INFO - Reading from file tmpfile_stdout 14:45:50 INFO - Output received: 14:45:50 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 14:45:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:45:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:45:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:45:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:45:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:45:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100bccc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3f954c4b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b55c60>, '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 0x1008eacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100913be8>, '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.vGdJP8YZLi/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.1S6GguDfGB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 14:45:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:45:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:45:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vGdJP8YZLi/Render', 14:45:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:45:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:45:50 INFO - 'HOME': '/Users/cltbld', 14:45:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:45:50 INFO - 'LOGNAME': 'cltbld', 14:45:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:45:50 INFO - 'MOZ_NO_REMOTE': '1', 14:45:50 INFO - 'NO_EM_RESTART': '1', 14:45:50 INFO - 'PAGER': '/bin/cat', 14:45:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:45:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:45:50 INFO - 'PWD': '/builds/slave/test', 14:45:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:45:50 INFO - 'SHELL': '/bin/bash', 14:45:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1S6GguDfGB/Listeners', 14:45:50 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 14:45:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:45:50 INFO - 'USER': 'cltbld', 14:45:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:45:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:45:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:45:50 INFO - 'XPC_FLAGS': '0x0', 14:45:50 INFO - 'XPC_SERVICE_NAME': '0', 14:45:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:45:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:45:50 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:45:50 INFO - Cleaning up... 14:45:50 INFO - Return code: 0 14:45:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:45:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:45:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:45:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:45:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:45:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100bccc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3f954c4b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b55c60>, '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 0x1008eacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100913be8>, '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.vGdJP8YZLi/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.1S6GguDfGB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 14:45:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:45:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:45:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vGdJP8YZLi/Render', 14:45:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:45:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:45:50 INFO - 'HOME': '/Users/cltbld', 14:45:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:45:50 INFO - 'LOGNAME': 'cltbld', 14:45:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:45:50 INFO - 'MOZ_NO_REMOTE': '1', 14:45:50 INFO - 'NO_EM_RESTART': '1', 14:45:50 INFO - 'PAGER': '/bin/cat', 14:45:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:45:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:45:50 INFO - 'PWD': '/builds/slave/test', 14:45:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:45:50 INFO - 'SHELL': '/bin/bash', 14:45:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1S6GguDfGB/Listeners', 14:45:50 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 14:45:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:45:50 INFO - 'USER': 'cltbld', 14:45:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:45:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:45:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:45:50 INFO - 'XPC_FLAGS': '0x0', 14:45:50 INFO - 'XPC_SERVICE_NAME': '0', 14:45:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:45:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:45:50 INFO - Downloading/unpacking psutil>=3.1.1 14:45:50 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 14:45:50 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 14:45:50 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 14:45:50 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 14:45:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:45:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:45:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:45:52 INFO - Installing collected packages: psutil 14:45:52 INFO - Running setup.py install for psutil 14:45:52 INFO - building 'psutil._psutil_osx' extension 14:45:52 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 14:45:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 14:45:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 14:45:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 14:45:53 INFO - building 'psutil._psutil_posix' extension 14:45:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 14:45:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:45:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:45:53 INFO - ^ 14:45:53 INFO - 1 warning generated. 14:45:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 14:45:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:45:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:45:53 INFO - Successfully installed psutil 14:45:53 INFO - Cleaning up... 14:45:54 INFO - Return code: 0 14:45:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:45: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')]} 14:45:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:45: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')]} 14:45:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:45:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100bccc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3f954c4b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b55c60>, '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 0x1008eacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100913be8>, '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.vGdJP8YZLi/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.1S6GguDfGB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 14:45:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:45:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:45:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vGdJP8YZLi/Render', 14:45:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:45:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:45:54 INFO - 'HOME': '/Users/cltbld', 14:45:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:45:54 INFO - 'LOGNAME': 'cltbld', 14:45:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:45:54 INFO - 'MOZ_NO_REMOTE': '1', 14:45:54 INFO - 'NO_EM_RESTART': '1', 14:45:54 INFO - 'PAGER': '/bin/cat', 14:45:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:45:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:45:54 INFO - 'PWD': '/builds/slave/test', 14:45:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:45:54 INFO - 'SHELL': '/bin/bash', 14:45:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1S6GguDfGB/Listeners', 14:45:54 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 14:45:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:45:54 INFO - 'USER': 'cltbld', 14:45:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:45:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:45:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:45:54 INFO - 'XPC_FLAGS': '0x0', 14:45:54 INFO - 'XPC_SERVICE_NAME': '0', 14:45:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:45:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:45:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:45:55 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 14:45:55 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 14:45:55 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 14:45:55 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 14:45:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:45:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:45:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:45:56 INFO - Installing collected packages: mozsystemmonitor 14:45:56 INFO - Running setup.py install for mozsystemmonitor 14:45:57 INFO - Successfully installed mozsystemmonitor 14:45:57 INFO - Cleaning up... 14:45:57 INFO - Return code: 0 14:45:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:45:57 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')]} 14:45:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:45:57 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')]} 14:45:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:45:57 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 0x100bccc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3f954c4b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b55c60>, '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 0x1008eacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100913be8>, '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.vGdJP8YZLi/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.1S6GguDfGB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 14:45:57 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 14:45:57 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 14:45:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vGdJP8YZLi/Render', 14:45:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:45:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:45:57 INFO - 'HOME': '/Users/cltbld', 14:45:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:45:57 INFO - 'LOGNAME': 'cltbld', 14:45:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:45:57 INFO - 'MOZ_NO_REMOTE': '1', 14:45:57 INFO - 'NO_EM_RESTART': '1', 14:45:57 INFO - 'PAGER': '/bin/cat', 14:45:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:45:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:45:57 INFO - 'PWD': '/builds/slave/test', 14:45:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:45:57 INFO - 'SHELL': '/bin/bash', 14:45:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1S6GguDfGB/Listeners', 14:45:57 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 14:45:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:45:57 INFO - 'USER': 'cltbld', 14:45:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:45:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:45:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:45:57 INFO - 'XPC_FLAGS': '0x0', 14:45:57 INFO - 'XPC_SERVICE_NAME': '0', 14:45:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:45:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:45:57 INFO - Downloading/unpacking jsonschema==2.5.1 14:45:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:45:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:45:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:45:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:45:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:45:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:45:58 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 14:45:58 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 14:45:58 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 14:45:58 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 14:45:58 INFO - Downloading functools32-3.2.3-2.tar.gz 14:45:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:45:59 INFO - warning: no files found matching '*.txt' 14:45:59 INFO - no previously-included directories found matching 'build' 14:45:59 INFO - no previously-included directories found matching 'dist' 14:45:59 INFO - no previously-included directories found matching '.git*' 14:45:59 INFO - Installing collected packages: jsonschema, functools32 14:45:59 INFO - Running setup.py install for functools32 14:45:59 INFO - warning: no files found matching '*.txt' 14:45:59 INFO - no previously-included directories found matching 'build' 14:45:59 INFO - no previously-included directories found matching 'dist' 14:45:59 INFO - no previously-included directories found matching '.git*' 14:45:59 INFO - Successfully installed jsonschema functools32 14:45:59 INFO - Cleaning up... 14:45:59 INFO - Return code: 0 14:45:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:45:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:45:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:45:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:45:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100bccc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3f954c4b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b55c60>, '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 0x1008eacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100913be8>, '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.vGdJP8YZLi/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.1S6GguDfGB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 14:45:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:45:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:45:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vGdJP8YZLi/Render', 14:45:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:45:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:45:59 INFO - 'HOME': '/Users/cltbld', 14:45:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:45:59 INFO - 'LOGNAME': 'cltbld', 14:45:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:45:59 INFO - 'MOZ_NO_REMOTE': '1', 14:45:59 INFO - 'NO_EM_RESTART': '1', 14:45:59 INFO - 'PAGER': '/bin/cat', 14:45:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:45:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:45:59 INFO - 'PWD': '/builds/slave/test', 14:45:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:45:59 INFO - 'SHELL': '/bin/bash', 14:45:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1S6GguDfGB/Listeners', 14:45:59 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 14:45:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:45:59 INFO - 'USER': 'cltbld', 14:45:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:45:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:45:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:45:59 INFO - 'XPC_FLAGS': '0x0', 14:45:59 INFO - 'XPC_SERVICE_NAME': '0', 14:45:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:45:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:45:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:45:59 INFO - Cleaning up... 14:45:59 INFO - Return code: 0 14:45:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:45:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:45:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:45:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:45:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100bccc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3f954c4b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b55c60>, '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 0x1008eacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100913be8>, '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.vGdJP8YZLi/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.1S6GguDfGB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 14:45:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:45:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:45:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vGdJP8YZLi/Render', 14:45:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:45:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:45:59 INFO - 'HOME': '/Users/cltbld', 14:45:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:45:59 INFO - 'LOGNAME': 'cltbld', 14:45:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:45:59 INFO - 'MOZ_NO_REMOTE': '1', 14:45:59 INFO - 'NO_EM_RESTART': '1', 14:45:59 INFO - 'PAGER': '/bin/cat', 14:45:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:45:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:45:59 INFO - 'PWD': '/builds/slave/test', 14:45:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:45:59 INFO - 'SHELL': '/bin/bash', 14:45:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1S6GguDfGB/Listeners', 14:45:59 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 14:45:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:45:59 INFO - 'USER': 'cltbld', 14:45:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:45:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:45:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:45:59 INFO - 'XPC_FLAGS': '0x0', 14:45:59 INFO - 'XPC_SERVICE_NAME': '0', 14:45:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:45:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:45:59 INFO - Downloading/unpacking blobuploader==1.2.4 14:45:59 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 14:45:59 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 14:45:59 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 14:45:59 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 14:46:01 INFO - Downloading blobuploader-1.2.4.tar.gz 14:46:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:46:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:46:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:46:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:46:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:46:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:46:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:46:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:46:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:46:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:46:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:46:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:46:02 INFO - Downloading docopt-0.6.1.tar.gz 14:46:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:46:02 INFO - Installing collected packages: blobuploader, requests, docopt 14:46:02 INFO - Running setup.py install for blobuploader 14:46:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:46:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:46:02 INFO - Running setup.py install for requests 14:46:02 INFO - Running setup.py install for docopt 14:46:02 INFO - Successfully installed blobuploader requests docopt 14:46:02 INFO - Cleaning up... 14:46:03 INFO - Return code: 0 14:46:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:46: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')]} 14:46:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:46: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')]} 14:46:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:46:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100bccc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3f954c4b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b55c60>, '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 0x1008eacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100913be8>, '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.vGdJP8YZLi/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.1S6GguDfGB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 14:46:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:46:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:46:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vGdJP8YZLi/Render', 14:46:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:46:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:46:03 INFO - 'HOME': '/Users/cltbld', 14:46:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:46:03 INFO - 'LOGNAME': 'cltbld', 14:46:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:46:03 INFO - 'MOZ_NO_REMOTE': '1', 14:46:03 INFO - 'NO_EM_RESTART': '1', 14:46:03 INFO - 'PAGER': '/bin/cat', 14:46:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:46:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:46:03 INFO - 'PWD': '/builds/slave/test', 14:46:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:46:03 INFO - 'SHELL': '/bin/bash', 14:46:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1S6GguDfGB/Listeners', 14:46:03 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 14:46:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:46:03 INFO - 'USER': 'cltbld', 14:46:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:46:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:46:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:46:03 INFO - 'XPC_FLAGS': '0x0', 14:46:03 INFO - 'XPC_SERVICE_NAME': '0', 14:46:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:46:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:46:03 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-LscMjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:46:03 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-FHcWdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:46:03 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-N3YWzI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:46:03 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-b7QcH_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:46:03 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-ngSN7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:46:03 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-PD6QJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:46:03 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-VhrHKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:46:04 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-10fe21-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:46:04 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-NKnoO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:46:04 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-bLfuG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:46:04 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-KWgP6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:46:04 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-vfG3yx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:46:04 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-QHXXgE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:46:04 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-p9HByA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:46:04 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-5Eu6Lb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:46:04 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-a01BXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:46:04 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-VjBP7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:46:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:46:05 INFO - Running setup.py install for manifestparser 14:46:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:46:05 INFO - Running setup.py install for mozcrash 14:46:05 INFO - Running setup.py install for mozdebug 14:46:05 INFO - Running setup.py install for mozdevice 14:46:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:46:05 INFO - Running setup.py install for mozfile 14:46:05 INFO - Running setup.py install for mozhttpd 14:46:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:46:05 INFO - Running setup.py install for mozinfo 14:46:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:46:06 INFO - Running setup.py install for mozInstall 14:46:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:46:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:46:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:46:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:46:06 INFO - Running setup.py install for mozleak 14:46:06 INFO - Running setup.py install for mozlog 14:46:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:46:06 INFO - Running setup.py install for moznetwork 14:46:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:46:06 INFO - Running setup.py install for mozprocess 14:46:06 INFO - Running setup.py install for mozprofile 14:46:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:46:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:46:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:46:06 INFO - Running setup.py install for mozrunner 14:46:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:46:07 INFO - Running setup.py install for mozscreenshot 14:46:07 INFO - Running setup.py install for moztest 14:46:07 INFO - Running setup.py install for mozversion 14:46:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:46:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:46:07 INFO - Cleaning up... 14:46:07 INFO - Return code: 0 14:46:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:46:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:46:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:46:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:46:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:46:07 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 0x100bccc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3f954c4b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b55c60>, '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 0x1008eacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100913be8>, '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.vGdJP8YZLi/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.1S6GguDfGB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 14:46:07 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 14:46:07 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 14:46:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vGdJP8YZLi/Render', 14:46:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:46:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:46:07 INFO - 'HOME': '/Users/cltbld', 14:46:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:46:07 INFO - 'LOGNAME': 'cltbld', 14:46:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:46:07 INFO - 'MOZ_NO_REMOTE': '1', 14:46:07 INFO - 'NO_EM_RESTART': '1', 14:46:07 INFO - 'PAGER': '/bin/cat', 14:46:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:46:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:46:07 INFO - 'PWD': '/builds/slave/test', 14:46:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:46:07 INFO - 'SHELL': '/bin/bash', 14:46:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1S6GguDfGB/Listeners', 14:46:07 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 14:46:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:46:07 INFO - 'USER': 'cltbld', 14:46:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:46:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:46:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:46:07 INFO - 'XPC_FLAGS': '0x0', 14:46:07 INFO - 'XPC_SERVICE_NAME': '0', 14:46:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:46:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:46:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:46:07 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-J9D_cu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:46:07 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)) 14:46:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:46:07 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-F5dB8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:46:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:46:08 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-vgq5Ti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:46:08 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)) 14:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:46:08 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-YWuo9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:46:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:46:08 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-I784VA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:46:08 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)) 14:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:46:08 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-afIiVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:46:08 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)) 14:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:46:08 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-njih_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:46:08 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)) 14:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:46:08 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-7bGR4r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:46:08 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)) 14:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:46:08 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-gTgd8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:46:08 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)) 14:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:46:08 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-_zrrsm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:46:08 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)) 14:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:46:08 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-aJogFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:46:08 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)) 14:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:46:09 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-HK3gpX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:46:09 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)) 14:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:46:09 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-QnYosR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:46:09 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)) 14:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:46:09 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-_C2cVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:46:09 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)) 14:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:46:09 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-tj_7yE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:46:09 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)) 14:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:46:09 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-ZCyYDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:46:09 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)) 14:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:46:09 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-QiZnUK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:46:09 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)) 14:46:09 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)) 14:46:09 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)) 14:46:09 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)) 14:46:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:46:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:46:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:46:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:46:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:46:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:46:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:46:11 INFO - Downloading blessings-1.6.tar.gz 14:46:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:46:11 INFO - Installing collected packages: blessings 14:46:11 INFO - Running setup.py install for blessings 14:46:11 INFO - Successfully installed blessings 14:46:11 INFO - Cleaning up... 14:46:11 INFO - Return code: 0 14:46:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:46:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:46:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:46:11 INFO - Reading from file tmpfile_stdout 14:46:11 INFO - Current package versions: 14:46:11 INFO - blessings == 1.6 14:46:11 INFO - blobuploader == 1.2.4 14:46:11 INFO - docopt == 0.6.1 14:46:11 INFO - functools32 == 3.2.3-2 14:46:11 INFO - jsonschema == 2.5.1 14:46:11 INFO - manifestparser == 1.1 14:46:11 INFO - mozInstall == 1.12 14:46:11 INFO - mozcrash == 1.0 14:46:11 INFO - mozdebug == 0.1 14:46:11 INFO - mozdevice == 0.51 14:46:11 INFO - mozfile == 1.2 14:46:11 INFO - mozhttpd == 0.7 14:46:11 INFO - mozinfo == 0.9 14:46:11 INFO - mozleak == 0.1 14:46:11 INFO - mozlog == 3.4 14:46:11 INFO - moznetwork == 0.27 14:46:11 INFO - mozprocess == 0.25 14:46:11 INFO - mozprofile == 0.28 14:46:11 INFO - mozrunner == 6.13 14:46:11 INFO - mozscreenshot == 0.1 14:46:11 INFO - mozsystemmonitor == 0.3 14:46:11 INFO - moztest == 0.8 14:46:11 INFO - mozversion == 1.4 14:46:11 INFO - psutil == 3.1.1 14:46:11 INFO - requests == 1.2.3 14:46:11 INFO - wsgiref == 0.1.2 14:46:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:46:11 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')]} 14:46:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:46:11 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')]} 14:46:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:46:11 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 0x100bccc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3f954c4b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b55c60>, '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 0x1008eacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100913be8>, '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.vGdJP8YZLi/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.1S6GguDfGB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 14:46:11 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 14:46:11 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 14:46:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vGdJP8YZLi/Render', 14:46:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:46:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:46:11 INFO - 'HOME': '/Users/cltbld', 14:46:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:46:11 INFO - 'LOGNAME': 'cltbld', 14:46:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:46:11 INFO - 'MOZ_NO_REMOTE': '1', 14:46:11 INFO - 'NO_EM_RESTART': '1', 14:46:11 INFO - 'PAGER': '/bin/cat', 14:46:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:46:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:46:11 INFO - 'PWD': '/builds/slave/test', 14:46:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:46:11 INFO - 'SHELL': '/bin/bash', 14:46:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1S6GguDfGB/Listeners', 14:46:11 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 14:46:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:46:11 INFO - 'USER': 'cltbld', 14:46:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:46:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:46:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:46:11 INFO - 'XPC_FLAGS': '0x0', 14:46:11 INFO - 'XPC_SERVICE_NAME': '0', 14:46:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:46:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:46:11 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)) 14:46:11 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)) 14:46:11 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)) 14:46:11 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)) 14:46:11 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)) 14:46:11 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)) 14:46:11 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)) 14:46:11 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)) 14:46:11 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)) 14:46:11 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 14:46:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:46:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:46:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:46:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:46:13 INFO - Downloading marionette_driver-1.4.0.tar.gz 14:46:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 14:46:13 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)) 14:46:13 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)) 14:46:13 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)) 14:46:13 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)) 14:46:13 INFO - Installing collected packages: marionette-driver 14:46:13 INFO - Running setup.py install for marionette-driver 14:46:13 INFO - Successfully installed marionette-driver 14:46:13 INFO - Cleaning up... 14:46:13 INFO - Return code: 0 14:46:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:46: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')]} 14:46:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:46: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')]} 14:46:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:46: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100bccc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3f954c4b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b55c60>, '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 0x1008eacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100913be8>, '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.vGdJP8YZLi/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.1S6GguDfGB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 14:46: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', 'jsonschema'] in /builds/slave/test/build 14:46: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 jsonschema 14:46:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vGdJP8YZLi/Render', 14:46:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:46:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:46:13 INFO - 'HOME': '/Users/cltbld', 14:46:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:46:13 INFO - 'LOGNAME': 'cltbld', 14:46:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:46:13 INFO - 'MOZ_NO_REMOTE': '1', 14:46:13 INFO - 'NO_EM_RESTART': '1', 14:46:13 INFO - 'PAGER': '/bin/cat', 14:46:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:46:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:46:13 INFO - 'PWD': '/builds/slave/test', 14:46:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:46:13 INFO - 'SHELL': '/bin/bash', 14:46:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1S6GguDfGB/Listeners', 14:46:13 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 14:46:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:46:13 INFO - 'USER': 'cltbld', 14:46:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:46:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:46:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:46:13 INFO - 'XPC_FLAGS': '0x0', 14:46:13 INFO - 'XPC_SERVICE_NAME': '0', 14:46:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:46:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:46:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:46:13 INFO - Cleaning up... 14:46:13 INFO - Return code: 0 14:46:13 INFO - Running post-action listener: _resource_record_post_action 14:46:13 INFO - Running post-action listener: _start_resource_monitoring 14:46:13 INFO - Starting resource monitoring. 14:46:13 INFO - [mozharness: 2017-05-30 21:46:13.956211Z] Finished create-virtualenv step (success) 14:46:13 INFO - [mozharness: 2017-05-30 21:46:13.957174Z] Running install step. 14:46:13 INFO - Running pre-action listener: _resource_record_pre_action 14:46:13 INFO - Running main action method: install 14:46:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:46:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:46:14 INFO - Reading from file tmpfile_stdout 14:46:14 INFO - Detecting whether we're running mozinstall >=1.0... 14:46:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:46:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:46:14 INFO - Reading from file tmpfile_stdout 14:46:14 INFO - Output received: 14:46:14 INFO - Usage: mozinstall [options] installer 14:46:14 INFO - Options: 14:46:14 INFO - -h, --help show this help message and exit 14:46:14 INFO - -d DEST, --destination=DEST 14:46:14 INFO - Directory to install application into. [default: 14:46:14 INFO - "/builds/slave/test"] 14:46:14 INFO - --app=APP Application being installed. [default: firefox] 14:46:14 INFO - mkdir: /builds/slave/test/build/application 14:46:14 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'] 14:46:14 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 14:46:35 INFO - Reading from file tmpfile_stdout 14:46:35 INFO - Output received: 14:46:35 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:46:35 INFO - Running post-action listener: _resource_record_post_action 14:46:35 INFO - [mozharness: 2017-05-30 21:46:35.727625Z] Finished install step (success) 14:46:35 INFO - [mozharness: 2017-05-30 21:46:35.727759Z] Running setup-mitmproxy step. 14:46:35 INFO - Running pre-action listener: _resource_record_pre_action 14:46:35 INFO - Running main action method: setup_mitmproxy 14:46:35 INFO - Skipping: mitmproxy is not required 14:46:35 INFO - Running post-action listener: _resource_record_post_action 14:46:35 INFO - [mozharness: 2017-05-30 21:46:35.728456Z] Finished setup-mitmproxy step (success) 14:46:35 INFO - [mozharness: 2017-05-30 21:46:35.728576Z] Running run-tests step. 14:46:35 INFO - Running pre-action listener: _resource_record_pre_action 14:46:35 INFO - Running main action method: run_tests 14:46:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:46:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:46:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:46:35 INFO - 2017-05-30 14:46:35.743 screenresolution[1631:7383] starting screenresolution argv=screenresolution get 14:46:35 INFO - 2017-05-30 14:46:35.746 screenresolution[1631:7383] Display 0: 1600x1200x32@60 14:46:35 INFO - 2017-05-30 14:46:35.751 screenresolution[1632:7387] starting screenresolution argv=screenresolution list 14:46:35 INFO - Available Modes on Display 0 14:46:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:46:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:46:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:46:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:46:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:46:35 INFO - Intel Iris: 14:46:35 INFO - Chipset Model: Intel Iris 14:46:35 INFO - Type: GPU 14:46:35 INFO - Bus: Built-In 14:46:35 INFO - VRAM (Dynamic, Max): 1536 MB 14:46:35 INFO - Vendor: Intel (0x8086) 14:46:35 INFO - Device ID: 0x0a2e 14:46:35 INFO - Revision ID: 0x0009 14:46:35 INFO - Displays: 14:46:35 INFO - Display: 14:46:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:46:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:46:35 INFO - Display Serial Number: 1600x1200 60 14:46:35 INFO - Main Display: Yes 14:46:35 INFO - Mirror: Off 14:46:35 INFO - Online: Yes 14:46:35 INFO - Rotation: Supported 14:46:35 INFO - Return code: 0 14:46:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:46:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:46:35 INFO - Python 2.7.3 14:46:35 INFO - Return code: 0 14:46:35 INFO - grabbing minidump binary from tooltool 14:46:35 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')]} 14:46:35 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 0x100b55c60>, '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 0x1008eacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100913be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:46:35 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 14:46:35 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 14:46:35 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 14:46:36 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:46:36 INFO - Return code: 0 14:46:36 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:46:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:46:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:46:36 INFO - ENV: RUST_BACKTRACE is now 1 14:46:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:46:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:46:36 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/GUpuU_giRxaWAyN0ULUEyQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0163', '--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 14:46:36 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/GUpuU_giRxaWAyN0ULUEyQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0163 --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 14:46:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vGdJP8YZLi/Render', 14:46:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:46:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:46:36 INFO - 'HOME': '/Users/cltbld', 14:46:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:46:36 INFO - 'LOGNAME': 'cltbld', 14:46:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:46:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:46:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:46:36 INFO - 'MOZ_NO_REMOTE': '1', 14:46:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:46:36 INFO - 'NO_EM_RESTART': '1', 14:46:36 INFO - 'PAGER': '/bin/cat', 14:46:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:46:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:46:36 INFO - 'PWD': '/builds/slave/test', 14:46:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:46:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:46:36 INFO - 'RUST_BACKTRACE': '1', 14:46:36 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 14:46:36 INFO - 'SHELL': '/bin/bash', 14:46:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1S6GguDfGB/Listeners', 14:46:36 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 14:46:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:46:36 INFO - 'USER': 'cltbld', 14:46:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:46:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:46:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:46:36 INFO - 'XPC_FLAGS': '0x0', 14:46:36 INFO - 'XPC_SERVICE_NAME': '0', 14:46:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:46:36 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/GUpuU_giRxaWAyN0ULUEyQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0163', '--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 14:46:36 INFO - mozversion application_buildid: 20170530131222 14:46:36 INFO - mozversion application_changeset: 692e277e2b9f3bb36d6d67e1166d135228f2d851 14:46:36 INFO - mozversion application_display_name: Nightly 14:46:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:46:36 INFO - mozversion application_name: Firefox 14:46:36 INFO - mozversion application_remotingname: firefox 14:46:36 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:46:36 INFO - mozversion application_vendor: Mozilla 14:46:36 INFO - mozversion application_version: 55.0a1 14:46:36 INFO - mozversion platform_buildid: 20170530131222 14:46:36 INFO - mozversion platform_changeset: 692e277e2b9f3bb36d6d67e1166d135228f2d851 14:46:36 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:46:36 INFO - mozversion platform_version: 55.0a1 14:46:36 INFO - using testdate: 1496180796 14:46:36 INFO - actual date: 1496180796 14:46:36 INFO - starting webserver on 'localhost:49228' 14:46:36 INFO - SUITE-START | Running 2 tests 14:46:36 INFO - TEST-START | dromaeo_css 14:46:36 INFO - operating with platform_type : mac_ 14:46:36 INFO - Initialising browser for dromaeo_css test... 14:46:36 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmpEiTmlM/profile 14:46:36 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 14:47:10 INFO - TEST-INFO | 1642: killed by SIGTERM 14:47:10 INFO - Browser initialized. 14:47:10 INFO - Running cycle 1/1 for dromaeo_css test... 14:47:10 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmpEiTmlM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:47:12 INFO - PID 1652 | 2017-05-30 14:47:12.648 plugin-container[1653:7962] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x984b, name = 'com.apple.tsm.portname' 14:47:12 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:47:12 INFO - PID 1652 | 2017-05-30 14:47:12.648 plugin-container[1653:7962] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0f, name = 'com.apple.CFPasteboardClient' 14:47:12 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:47:12 INFO - PID 1652 | 2017-05-30 14:47:12.648 plugin-container[1653:7962] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:47:13 INFO - PID 1652 | 2017-05-30 14:47:13.192 plugin-container[1654:8003] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 14:47:13 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:47:13 INFO - PID 1652 | 2017-05-30 14:47:13.193 plugin-container[1654:8003] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 14:47:13 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:47:13 INFO - PID 1652 | 2017-05-30 14:47:13.193 plugin-container[1654:8003] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:47:22 INFO - PID 1652 | RSS: Main: 262332416 14:47:22 INFO - PID 1652 | 14:50:24 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 14:50:26 INFO - PID 1652 | RSS: Main: 271589376 14:50:26 INFO - PID 1652 | 14:53:28 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 14:53:29 INFO - PID 1652 | RSS: Main: 284299264 14:53:29 INFO - PID 1652 | 14:56:30 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 14:56:31 INFO - PID 1652 | RSS: Main: 284082176 14:56:31 INFO - PID 1652 | 14:59:33 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 14:59:33 INFO - PID 1652 | RSS: Main: 288546816 14:59:33 INFO - PID 1652 | 15:02:36 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 15:02:37 INFO - PID 1652 | RSS: Main: 295043072 15:02:37 INFO - PID 1652 | 15:05:39 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 15:05:39 INFO - PID 1652 | RSS: Main: 294916096 15:05:39 INFO - PID 1652 | 15:05:39 INFO - PID 1652 | __start_tp_report 15:05:39 INFO - PID 1652 | _x_x_mozilla_page_load 15:05:39 INFO - PID 1652 | _x_x_mozilla_page_load_details 15:05:39 INFO - PID 1652 | |i|pagename|runs| 15:05:39 INFO - PID 1652 | |0;dojo.html;3549.946750798738;3555.128993396618;3577.141486043347;3577.8747743828962;3596.7122630189588;4840.636952228584;6051;6137.202163718716;6180.134781130645;6202.1006953991755;6060.151578778975;6129.4789942854795;6139.6623185724875;6156.938430615693;6219.689015549227;4378.97810510947;4607.953920460786;5278.81524146653;5298.09932311508;5395.595330350229;4900.117978763821;5395.73021348933;5487.259220005308;5487.75305111271;5497.285352904117;5316.4683531646915;5349.5720342372515;5380.542653874425;5409.43200963901;5437.157240627709;4841.789552611847;5113.463086375914;5372.140457526777;5388.622796404214;5423.322084739408;5297.682139071668;5342.0651386007685;5359.785608575652;5365.946340536622;5375.569954403638;5819.563532735062;6034.0948857671265;6157.4458298753325;6190.628562286232;6195.287541932646;6424.38968298011;6452.483801295885;6527.379898909596;6533.869322613568;6551.05009773585;6485.967570162166;6638.17022872141;6656.101426307435;6683.298253683391;6688;6128.3565225651555;6266.749330026793;6289.496840252768;6303;6326.68366581669;5571.888562228733;6584.9012264816065;6695.296993815677;6802.761903333359;6812.386885180357;6301.495880329563;6336.778212762531;6396.456301214356;6411.743530258737;6480.060391243243;6530.444912182423;6648.96675516629;6655.900161497581;6659.4006539411685;6676.86646267072;6536.444402225864;6564.146660934143;6565.24499682533;6580.374864387875;6588.571742836701;6044.153818465418;6419.839504012343;6450.709718062699;6499.772507962199;6535.477161827042;5254.7898084076205;5256.001359741714;5268.710220937808;5286.20706893969;5297.523222909879;4700.882477938079;4704.059188162313;4708.340832283483;4717.3159891816185;4728.1962066448095;4826.420829500412;6230.750769969241;6240.875182496325;6282;6294.590851594632;6630;6662.200535935622;6681.064650948871;6686.231083425469;6688.197416310074;6251.562390632703;6311.684415779285;6328.082428047999;6334.493240540745;6342.778002769973;4511.684182107221;5676.460736230051;5807.12893066035;5813.709314534256;5819.854503637358;6817.147856517935;6846.4522838173825;6860.5540639858245;6864;6865.622390768555;6674.899876501953;6680.064790929371;6682.3317668232785;6700.731970721116;6705.530612857053;6553.475721942328;6648.7340506379205;6765.560238584476;6784.3215678433735;6856.314368563104;6433.613983161025;6500;6520.119783829123;6526.151600291932;6627.900581491185;6652.900206497004;6674.799756007327;6678.23200331968;6682.866342673022;6689.431398331197;6290.559660823698;6343.841403964753;6372.553921225655;6425.646589437626;6442;6408.519361047846;6422.775202867877;6436.903446448214;6448.838779030561;6454.1609590752905;6208.50331973432;6279.340669229665;6352.110704501281;6367.554271201157;6422.9036564450635;6234.625922444668;6345.333739957238;6374.394432528902;6439.291677915332;6467.838304042437;6286.6856657166045;6324.525660575383;6327.493800496041;6331.145295385064;6338.271098823694 15:05:39 INFO - PID 1652 | |1;ext.html;774.4810976645647;821.080389963241;840.504102579478;1250.712336162682;1322.7685155097868;38666;39234.68418473966;39263.0184245394;39278.072192780746;39286.21427571447;39881.800590997074;40338.99152521188;40380.59619403805;40397.596024039754;40399.19201615966;7852.254035866584;8361.749147525554;8565.48607083577;8581.399302048829;8590.281943611302;8468.703595374163;8591.828163436727;8598.011228708685;8598.097199794025;8604.31165506758;8560.700375486858;8566.100559441262;8597.18326758957;8603.827923441528;8616.310695144373;8562.400631955765;8589.398742088057;8589.742307730778;8596.742097737078;8616.26761725254;8321.292690121287;8381;8386.790330241733;8390.706325278587;8392.538410387426;37308.88073357804;37526.43710344347;37571.248575028345;37702.43446348307;37803.24393512114;3498.1079824644808;3522.7710198836994;3526.3123690880034;3526.312369088029;3533.293341331744;30995.845020774977;31034.844825775952;31040;31055.22361940947;31126.844365778026;2842.2325971987448;2953.6708481183555;3037.271054946819;3138.3252600690735;3152.3064925716308;251824;255320;272442;273677;282740;47885;48199.5180048195;48274.03451931012;48426.5157348422;48450;50753.746231268604;50809;50924.49075509272;50937;50945.23582146271;14043.227622480657;14131.222782746841;14219.786703199461;14247.358868850926;14251.78622320666;11527.250728702607;11652.41737913101;11679.474423650956;11690.941545292219;11730.413479326;11370.317780933005;13239.073264871558;13593.728125437634;13627.114237574526;13664.248466334448;5208.427073021964;5835.737391817293;5943.61366511184;5986.161937328778;6026.758929642853;747.7196051480695;858.3862538285156;873.1835733589114;899.3614533681028;923.2429407885469;21674.89162554177;22984.015625622927;23428.882855585955;24158.75841241565;24557.38606534851;1113.204059097749;1119.7200699825044;1144.210494758614;1154.1747650429984;1157.6064138193055;11792.941035294769;12209.145359824726;12343.197692150161;12402.5659101931;12418.75162496745;322961;324125;324442;324767;324916;54600.45399546033;54967;54981;55152.172717409274;55179;1326.602019394178;1489.9180545070126;1514.833368329483;1535.708215439063;1539.9538013859378;19623.9394863543;22021.449463763533;23478.295651130495;23748.406289842893;23795.524089518458;38310;38335.42496862605;38370.61629383782;38394.616053839105;38478.2304353917;275404;277569;278504;278636;279573;27785.44429111447;28197.295067623472;31308;34577.962661120204;34599.481007785405;39165.41251881281;40633.39049914195;41313;41447.171056579304;41484;28640.85679571589;29556.556651650673;29593.85203073971;29620.703792961795;29734.702652974058;28784.992525261514;28813.56779648265;29110.417791643624;29396.265093372836;29531.852340738158;34627;34870.47694284537;35037.649623503436;35179.472307915916;35188.64811351854;27941.58087628728;28736;29101.563476547446;29165.708342917147;29749.851250743606;18709.81290187135;23201.767982320634;23531;23540.293791185606;23561.29316120519 15:05:39 INFO - PID 1652 | |2;jquery.html;424.89951026840475;430.09465076015005;435.4339358833516;442.3144126603763;454.34574213133084;21998;22800.42998925028;22887.656685149715;23324.067037318528;24591.13931012415;19511;20871;23673.644895326583;24662.89016994235;24702.629460558106;6157.230346206713;6282.371762823726;6310.495160387158;6312.5896816707;6324.968375158118;5191.325127733408;6253.749850005995;6284.12022316876;6296.307406185316;6317.084022816689;5228.503292187236;5879.52963762898;6132.018876979684;6180.443760061594;6188.133661287423;5826.82519524415;6068.514518838482;6134.04922237054;6135.846603834895;6147.569670123094;5419.5393391561765;5458.563314934796;5465.562754979592;5472.26124473195;5478.096114141162;28822.711772882212;29216.269593260127;29606.407871842657;29682.406351873058;29700.40599188004;2124.702541644171;2202;2202.7246594175726;2204;2206.911723531057;18947.715784263248;19182.232710691555;19185.944773037478;19234.71147932782;19289.807101928942;2099.5695882344085;2152.4834039830484;2181.7054697615777;2183.159483598826;2208.8012078912975;170327.29672703237;178676;184799;186066;196132.0386796128;12479.064070194772;12763;12766.744665106831;12815.551455699006;12844.100912936192;16461.25924333408;16592.668146637;16604.83395166057;16631.91684041596;16635;7607.619619019027;8946.642134314685;9137.862932056025;9154.908450915405;9188.127127922837;8612.18184272493;8612.827743445221;8750.781230469162;8847.823043539092;8850.20348168655;7198.064251647253;8190.795230119175;8238.876416853753;8290.917090829134;8309.04445988719;2011.7787043425212;2039.3881835449306;2202.119152339077;2206.018321846795;2325.3141472432353;930.4091901642506;969.9563519641633;974.8050389922043;978.9804203915882;1016.7407311135613;21455.034523446484;22005.11979520833;22014.88992555027;22050.88974555149;22266.666000010013;862.7411776467035;961.744922875654;1007.4660429972126;1014.9746256343503;1037.0821822686976;10556.736081598021;10715.051431249676;10950.452477376259;11142.331460112418;11318.26431281964;497786;499253;505588;510374;562599;122954.77045229611;144730;153269;153554;155159.4484055145;1022.5194158745378;1054.8734151901667;1154.2497376705041;1168.5092261250295;1238.2013601227159;18333.08334583292;19725.21099156075;21116.788832112095;23272.185473508347;23366.065357385512;51829.7408512955;66478;66872;67271.66364168147;68219;59594;68354.50632189935;69049.61900762057;87319.1268087311;89347.10652893648;10111.7472063199;10210.132138768287;10244.231682623684;10252.846207307046;10268.229882758673;15334.696550792862;16742.82800203974;17030;18314.908425457787;18435.98602076845;13260.336983150612;14626.268686565416;14755.55733328002;15208.391664333303;15302.464413745465;13319;13708.45166193381;13715.657108572366;13789;13987.930060349634;13908.304584770518;15120.319585618894;15266.236688165325;15290.61773455628;15547.611309717347;9143.679971201242;9347.915187987812;10078.697639070839;10165.288429810138;10179.440130792878;14540.200288984208;14590.927045364706;14599.562013139623;14737.557873263819;14773.704525909636 15:05:39 INFO - PID 1652 | |3;mootools.html;1354.4988354308907;1362.8705272999068;1366.5968539280923;1461.8611231932962;1499.2278976327186;11649.767004659901;11697.883021169786;12139.271643701384;12169.817452738205;12451;11850.229735067214;12005.75988480232;12074.275543467398;12076.577319793809;12129.029677625791;2526.191618682018;2557.105013245368;2580.380708629925;2582.6384306197056;2584.082650659017;2517.874106294687;2538.8984440622353;2555.399481121935;2560.2703229579574;2560.2703229579574;2272.3864556569715;2350.095213342858;2516.766784275701;2525.0404846158435;2525.722170561246;2042.805933436321;2482.2429159106373;2504.0484615846044;2505.8621775802326;2507.736687647798;1995.7605087389607;2280.6579013147993;2468.827182097254;2495.6880389951257;2520.596704527267;11766.647000589996;11840.348780817052;11841.940790295994;11860.703482412922;11902.88097119035;6443.742250309982;6701.229358623772;6708.563943343667;6709.899351509731;6728.596284222962;4097.364908439196;4269.6370808481315;4310.418093557361;4317.892052698676;4321.719088259253;1223.2843786384888;1223.5044806853239;1224.179799534314;1245.64499117751;1248.7752204603169;15428.3828646854;15876.206189690469;16440.75338869918;16533.594644455236;16606.66786664272;5504.6421982571;5516.14499752539;5553.611247212656;5680.147977803363;5696.174054762119;12064.698382540506;12101.878981210251;12137.271763694207;12148;12157.148999569945;6450.903236451457;6472.126262954535;6556.704948277244;6560.9343906560325;6562.934370656328;6050.455459008623;6126.326104128542;6176.197094377702;6186.1648677427975;6205.844853878689;4319.29387891781;4417.3815665806815;4442.155990361821;4462.665300102505;4467.955320446754;1247.3015111537386;1261.306281545165;1265.9620211393674;1279.7376537809728;1283.6919139406475;731.7987553422852;766.6933226709361;791.5092642561649;800.3517151107621;800.5036877136097;6181.289151747606;6729.3270672933295;6818.454523638097;7022.683979220948;7091.9290807091265;898.9955050224708;953.6614501851882;956.3783540698463;958.8226178156924;965.0494263150791;3579.7533304384015;3878.26313000529;3916.118873253495;3962.445257663929;4064.4716186895516;13822.93088534551;15291.311890964711;15351.232438378036;15359.539213823604;15367.308471118826;15005.399784008736;15069.47256846005;15137.697246055017;15258.542243732705;15259.694806103817;8090.705487121914;8112.797180070427;8565.528895910784;8600.741977740678;8700.911781172474;5496.367917689517;6952.478564107611;7141.928580714126;7177.138743350832;7267.600281984542;12801.487940482277;12880.871191287968;13086.280254586089;13145.408456619667;13248.536301229411;12910.870891290966;12926.482940682268;13173.93413032967;13274.203547787163;13471.326433678472;8442.788930276793;8572.52851093196;8923.776905577413;9566.234701224024;9600.567974441026;8781.385303028726;10087.848682269625;10653.520591573553;10684.57261709523;10804.567817287221;9142.49716265585;9268.397554158959;9283.860742088738;9302.116298951589;9327.86008209891;9011.819763604823;9125.22435592982;9149.451032938045;9191.586378612845;9231.446113233227;8161.102278749451;8233.300169485421;8331.666733330841;8338.958305208678;8350.87473687883;5856.267966504187;5869.647821130746;5954.553408494294;5968.134620480093;6088.665123418255;5948.078047902582;6082.513398928164;6115.235595550556;6124.601900876429;6241.469475094668 15:05:39 INFO - PID 1652 | |4;prototype.html;376.2287311012424;389.3879339534328;390.0093761844913;401.6444499813131;402.27389561840903;5291.206319052135;6305.6216627002295;6400.711967961442;6462.709178086986;6469.320721324258;5662.546996240301;5938.495227905622;6100.871338802313;6181.536384771149;6269.811905642838;3636.5454318210222;3857.9807100964454;3952.604739526046;4574.565416285464;4576.771161441931;4014.85576610666;4123.484564429446;4546.931796023062;4593.724376537401;4598.195315819735;4526.207913615121;4532.161550113223;4536.614387777026;4541.954580454203;4549.909001819961;3809.3524100902914;3991.2017596480528;4283.528811830696;4530.682852200331;4565.3836732041245;3857.112864041258;3866.7486613370043;3872.961270387288;3883.611638836094;3894.883153505399;5521.641093328961;5637.63355381903;6147.754089836401;6149.41580549847;6213.328268844395;4714.387129673155;4975.825846095369;5101.632922271283;5388.46115388458;5425.4808653577065;5712.571557133231;5867.618604790675;6724.462043036546;6726.93273067268;6762.8309292267595;3918.2172111689115;4368.978155109236;4371.14762621289;4372.781360931941;4389.18800021997;5334.3598768147685;6294.055891616248;6801.591904485697;7087.397571206454;7236.384907282887;4120.423140760296;4171.812268447927;4362.8036738346855;4417.491988421309;4432.911341773211;3830.7701537907815;4510.819567217274;4519.751413672279;4551.249043907718;4552.294394368878;4445.555444455593;5240.895182096413;5332.573678976057;5366.731663416814;5371.973140134352;6291.811245662638;6726.150308665156;6728.697208625624;6740.325967403221;6743.393094621508;1364.979525307121;1414.8514405987428;1465.4577806211769;1537.9000364976241;1542.105578764314;976.1848856205148;1002.5338217728792;1009.6668099527138;1013.8377859542438;1020.3877673396051;5213.843584692389;5290.047791397508;5313.884084342331;6349.52378571609;6379.681015949184;6446.419822215929;6726.293739157416;7019.876819708924;7035.788926332219;7105.538140020985;5237.476252374732;6279.340669229757;6292;6306.558540902184;6324.3043265240785;3642.8724994625063;3649.5949059611708;3651.434027725707;3673.9081522962138;3675.650813172744;6205.317415084337;7174.641267936582;7205.603691797001;7244.4062306292535;7258.129024516986;5948.196993405922;6988.44092472601;7127.89308160378;7217.711291548279;7241.456890733216;7148.892766608505;7219.639018049182;7227.9638601806655;7253.274672532704;7271.563706177646;5956.374580668968;6866.828329291808;6951.721931122698;7003.194632617314;7067.893981590383;6841.486888483483;6885.139357580302;6886.793396198122;6937.479689023445;6939.757108501179;6718.93281067183;6721.798346049626;6884.8967265490055;7250.3112204340505;7262.527935684164;4237.855778939607;4240.915181696287;4246.745195288293;4986.12742770021;4995.47547507521;6318.115463834974;7036.753713619999;7069.787906362817;7072.3634872861685;7080.575165490087;5857.502112320501;6717.361850624183;6741.11269329732;6758.4931130166415;6761.526693131434;6724.327567243236;6741.393274605309;6750.594964302158;6780.593164410151;6786.219584747817;6522.6412547308455;6568.784774816674;6600.471962242913;6634.469242460688;6667.933320666732;5859.179714839841;6430.971044632836;6751.729930802713;6783.762568310085;6801.557898736566;5357.740930881162;6190.442860142609;6575.4410875076155;6621.966890165519;6626.204855417381 15:05:39 INFO - PID 1652 | |5;yui.html;833.5707110837923;844.8479273730729;848.6053984897023;862.1766213266327;875.6147295190116;14942.103473791552;15483.225838708076;17094.40169594063;17544.473665790014;17576.912115439438;16556.917215413938;17434;18515.2593896244;18638.720419193724;18664.626707465843;980.0640388429068;988.7676396046924;1001.1990407673868;1002.3334482569082;1004.6634377483551;1008.8789345278577;1010.7220514358537;1011.8869243831763;1014.1683819268203;1016.1261315268863;938.5964035464748;1041.4428280869736;1045.0333441566559;1045.142982754142;1046.5656752447726;1038.8285932821075;1041.2190856857394;1041.7551875309296;1041.9218558608134;1045.2056437107776;748.9101307843039;752.7403045949138;759.0436050576259;759.1762937213131;765.873630850909;17227.569310767205;17479.863808852384;17571.5607109822;17673.293068277253;17675.20461579232;17410.825891741173;17591.912040439714;17819;17840.821591784046;17885.105744712844;14651.120932743963;14781;14791.186484743335;14951.775723364159;14984.925075374662;3561.928761424783;3602.4596310553366;3627.836747346376;3639.727020473475;3665.9266814663824;131617.34191328983;148954;149306;150211.24894375567;150786.24606877004;13715.862841371658;13903.165810051429;14036;14118;14216.289185540682;22015;22398.888005559867;26675.466490670467;27339.496327702167;27381.86309068442;6826.590404575742;7059.647017649097;7644.617769111522;7969.681212751425;7997.120316765151;3940.4286378474962;4041.8787436376956;4059.6752259819145;4068.2880495913096;4080.959190408117;4350.934735978963;4365.257906155961;4432.778361081933;4437.134758722018;4446.644268458515;944.7779771753633;949.781550243434;952.876126103616;966.8791401074866;967.5452537307455;638.6232123047424;666.2171947961126;676.2898956096079;677.7153595489906;693.3586432549871;9921.950390248003;11899.643010709693;12385.504579816887;12649.367531623566;12681.30252836103;648.7275344355382;649.5647915896361;653.8855700252532;662.4634046422414;677.204284965164;4950.727709975986;5014.749262536931;5048.116579598555;5051.6716413433005;5177.974110129425;147545.52454475532;148296;148846.25576872047;152492.71260931095;153686.86944323787;117816;120701.39649301698;129317;130312.3484382572;130901.69098308895;97673.51163244281;100645.49031764676;101773;104951;105085;9805.901940980784;11532.538698451968;12038.939805300917;12237.938810305892;12905.935470322589;38998.830035098996;39047.80476097715;43468.13063738644;44156;44241;58407.70796145992;58607.4139258619;58890.93881714119;59066.45601263938;59560.51098722393;17447.56381090432;17551.82448175502;17718.134198054377;17780.3776867809;17862;17952.19215135296;18293.908530457797;19139.712904306725;19177.808221917603;19204.903975480032;17210.483685489457;17610.295588176843;17978.730319044964;18316.81683183151;18399.72400413968;17370.39203627867;17830.732539012184;17955.551111222325;17981;18009.189586468903;16769.74845377345;16879.240434180738;16941.98348099118;17711.11444427748;17770.91114544419;9226.907730922874;11896;11955.521779128738;12036.75926481483;12678.366081696062;11108.555657773599;13116.808833403871;13309.267990260241;13866.861331386557;13878.583642490741 15:05:39 INFO - PID 1652 | __end_tp_report 15:05:39 INFO - PID 1652 | __start_cc_report 15:05:39 INFO - PID 1652 | _x_x_mozilla_cycle_collect,451 15:05:39 INFO - PID 1652 | __end_cc_report 15:05:39 INFO - PID 1652 | __startTimestamp1496181939355__endTimestamp 15:05:39 INFO - PID 1652 | 15:05:39 INFO - PID 1652 | ------- Summary: start ------- 15:05:39 INFO - PID 1652 | Number of tests: 6 15:05:39 INFO - PID 1652 | 15:05:39 INFO - PID 1652 | [#0] dojo.html Cycles:165 Average:6048.22 Median:6328.08 stddev:745.71 (11.8%) stddev-sans-first:721.78 15:05:39 INFO - PID 1652 | Values: 3549.9 3555.1 3577.1 3577.9 3596.7 4840.6 6051.0 6137.2 6180.1 6202.1 6060.2 6129.5 6139.7 6156.9 6219.7 4379.0 4608.0 5278.8 5298.1 5395.6 4900.1 5395.7 5487.3 5487.8 5497.3 5316.5 5349.6 5380.5 5409.4 5437.2 4841.8 5113.5 5372.1 5388.6 5423.3 5297.7 5342.1 5359.8 5365.9 5375.6 5819.6 6034.1 6157.4 6190.6 6195.3 6424.4 6452.5 6527.4 6533.9 6551.1 6486.0 6638.2 6656.1 6683.3 6688.0 6128.4 6266.7 6289.5 6303.0 6326.7 5571.9 6584.9 6695.3 6802.8 6812.4 6301.5 6336.8 6396.5 6411.7 6480.1 6530.4 6649.0 6655.9 6659.4 6676.9 6536.4 6564.1 6565.2 6580.4 6588.6 6044.2 6419.8 6450.7 6499.8 6535.5 5254.8 5256.0 5268.7 5286.2 5297.5 4700.9 4704.1 4708.3 4717.3 4728.2 4826.4 6230.8 6240.9 6282.0 6294.6 6630.0 6662.2 6681.1 6686.2 6688.2 6251.6 6311.7 6328.1 6334.5 6342.8 4511.7 5676.5 5807.1 5813.7 5819.9 6817.1 6846.5 6860.6 6864.0 6865.6 6674.9 6680.1 6682.3 6700.7 6705.5 6553.5 6648.7 6765.6 6784.3 6856.3 6433.6 6500.0 6520.1 6526.2 6627.9 6652.9 6674.8 6678.2 6682.9 6689.4 6290.6 6343.8 6372.6 6425.6 6442.0 6408.5 6422.8 6436.9 6448.8 6454.2 6208.5 6279.3 6352.1 6367.6 6422.9 6234.6 6345.3 6374.4 6439.3 6467.8 6286.7 6324.5 6327.5 6331.1 6338.3 15:05:39 INFO - PID 1652 | 15:05:39 INFO - PID 1652 | [#1] ext.html Cycles:180 Average:43983.50 Median:34588.72 stddev:76278.01 (220.5%) stddev-sans-first:76423.00 15:05:39 INFO - PID 1652 | Values: 774.5 821.1 840.5 1250.7 1322.8 38666.0 39234.7 39263.0 39278.1 39286.2 39881.8 40339.0 40380.6 40397.6 40399.2 7852.3 8361.7 8565.5 8581.4 8590.3 8468.7 8591.8 8598.0 8598.1 8604.3 8560.7 8566.1 8597.2 8603.8 8616.3 8562.4 8589.4 8589.7 8596.7 8616.3 8321.3 8381.0 8386.8 8390.7 8392.5 37308.9 37526.4 37571.2 37702.4 37803.2 3498.1 3522.8 3526.3 3526.3 3533.3 30995.8 31034.8 31040.0 31055.2 31126.8 2842.2 2953.7 3037.3 3138.3 3152.3 251824.0 255320.0 272442.0 273677.0 282740.0 47885.0 48199.5 48274.0 48426.5 48450.0 50753.7 50809.0 50924.5 50937.0 50945.2 14043.2 14131.2 14219.8 14247.4 14251.8 11527.3 11652.4 11679.5 11690.9 11730.4 11370.3 13239.1 13593.7 13627.1 13664.2 5208.4 5835.7 5943.6 5986.2 6026.8 747.7 858.4 873.2 899.4 923.2 21674.9 22984.0 23428.9 24158.8 24557.4 1113.2 1119.7 1144.2 1154.2 1157.6 11792.9 12209.1 12343.2 12402.6 12418.8 322961.0 324125.0 324442.0 324767.0 324916.0 54600.5 54967.0 54981.0 55152.2 55179.0 1326.6 1489.9 1514.8 1535.7 1540.0 19623.9 22021.4 23478.3 23748.4 23795.5 38310.0 38335.4 38370.6 38394.6 38478.2 275404.0 277569.0 278504.0 278636.0 279573.0 27785.4 28197.3 31308.0 34578.0 34599.5 39165.4 40633.4 41313.0 41447.2 41484.0 28640.9 29556.6 29593.9 29620.7 29734.7 28785.0 28813.6 29110.4 29396.3 29531.9 34627.0 34870.5 35037.6 35179.5 35188.6 27941.6 28736.0 29101.6 29165.7 29749.9 18709.8 23201.8 23531.0 23540.3 23561.3 15:05:39 INFO - PID 1652 | 15:05:39 INFO - PID 1652 | [#2] jquery.html Cycles:180 Average:36707.21 Median:2203.36 stddev:89677.51 (4070.0%) stddev-sans-first:89887.70 15:05:39 INFO - PID 1652 | Values: 424.9 430.1 435.4 442.3 454.3 21998.0 22800.4 22887.7 23324.1 24591.1 19511.0 20871.0 23673.6 24662.9 24702.6 6157.2 6282.4 6310.5 6312.6 6325.0 5191.3 6253.7 6284.1 6296.3 6317.1 5228.5 5879.5 6132.0 6180.4 6188.1 5826.8 6068.5 6134.0 6135.8 6147.6 5419.5 5458.6 5465.6 5472.3 5478.1 28822.7 29216.3 29606.4 29682.4 29700.4 2124.7 2202.0 2202.7 2204.0 2206.9 18947.7 19182.2 19185.9 19234.7 19289.8 2099.6 2152.5 2181.7 2183.2 2208.8 170327.3 178676.0 184799.0 186066.0 196132.0 12479.1 12763.0 12766.7 12815.6 12844.1 16461.3 16592.7 16604.8 16631.9 16635.0 7607.6 8946.6 9137.9 9154.9 9188.1 8612.2 8612.8 8750.8 8847.8 8850.2 7198.1 8190.8 8238.9 8290.9 8309.0 2011.8 2039.4 2202.1 2206.0 2325.3 930.4 970.0 974.8 979.0 1016.7 21455.0 22005.1 22014.9 22050.9 22266.7 862.7 961.7 1007.5 1015.0 1037.1 10556.7 10715.1 10950.5 11142.3 11318.3 497786.0 499253.0 505588.0 510374.0 562599.0 122954.8 144730.0 153269.0 153554.0 155159.4 1022.5 1054.9 1154.2 1168.5 1238.2 18333.1 19725.2 21116.8 23272.2 23366.1 51829.7 66478.0 66872.0 67271.7 68219.0 59594.0 68354.5 69049.6 87319.1 89347.1 10111.7 10210.1 10244.2 10252.8 10268.2 15334.7 16742.8 17030.0 18314.9 18436.0 13260.3 14626.3 14755.6 15208.4 15302.5 13319.0 13708.5 13715.7 13789.0 13987.9 13908.3 15120.3 15266.2 15290.6 15547.6 9143.7 9347.9 10078.7 10165.3 10179.4 14540.2 14590.9 14599.6 14737.6 14773.7 15:05:39 INFO - PID 1652 | 15:05:39 INFO - PID 1652 | [#3] mootools.html Cycles:180 Average:7109.49 Median:3897.19 stddev:4496.68 (115.4%) stddev-sans-first:4488.50 15:05:39 INFO - PID 1652 | Values: 1354.5 1362.9 1366.6 1461.9 1499.2 11649.8 11697.9 12139.3 12169.8 12451.0 11850.2 12005.8 12074.3 12076.6 12129.0 2526.2 2557.1 2580.4 2582.6 2584.1 2517.9 2538.9 2555.4 2560.3 2560.3 2272.4 2350.1 2516.8 2525.0 2525.7 2042.8 2482.2 2504.0 2505.9 2507.7 1995.8 2280.7 2468.8 2495.7 2520.6 11766.6 11840.3 11841.9 11860.7 11902.9 6443.7 6701.2 6708.6 6709.9 6728.6 4097.4 4269.6 4310.4 4317.9 4321.7 1223.3 1223.5 1224.2 1245.6 1248.8 15428.4 15876.2 16440.8 16533.6 16606.7 5504.6 5516.1 5553.6 5680.1 5696.2 12064.7 12101.9 12137.3 12148.0 12157.1 6450.9 6472.1 6556.7 6560.9 6562.9 6050.5 6126.3 6176.2 6186.2 6205.8 4319.3 4417.4 4442.2 4462.7 4468.0 1247.3 1261.3 1266.0 1279.7 1283.7 731.8 766.7 791.5 800.4 800.5 6181.3 6729.3 6818.5 7022.7 7091.9 899.0 953.7 956.4 958.8 965.0 3579.8 3878.3 3916.1 3962.4 4064.5 13822.9 15291.3 15351.2 15359.5 15367.3 15005.4 15069.5 15137.7 15258.5 15259.7 8090.7 8112.8 8565.5 8600.7 8700.9 5496.4 6952.5 7141.9 7177.1 7267.6 12801.5 12880.9 13086.3 13145.4 13248.5 12910.9 12926.5 13173.9 13274.2 13471.3 8442.8 8572.5 8923.8 9566.2 9600.6 8781.4 10087.8 10653.5 10684.6 10804.6 9142.5 9268.4 9283.9 9302.1 9327.9 9011.8 9125.2 9149.5 9191.6 9231.4 8161.1 8233.3 8331.7 8339.0 8350.9 5856.3 5869.6 5954.6 5968.1 6088.7 5948.1 6082.5 6115.2 6124.6 6241.5 15:05:39 INFO - PID 1652 | 15:05:39 INFO - PID 1652 | [#4] prototype.html Cycles:180 Average:5367.95 Median:6028.62 stddev:1748.76 (29.0%) stddev-sans-first:1713.06 15:05:39 INFO - PID 1652 | Values: 376.2 389.4 390.0 401.6 402.3 5291.2 6305.6 6400.7 6462.7 6469.3 5662.5 5938.5 6100.9 6181.5 6269.8 3636.5 3858.0 3952.6 4574.6 4576.8 4014.9 4123.5 4546.9 4593.7 4598.2 4526.2 4532.2 4536.6 4542.0 4549.9 3809.4 3991.2 4283.5 4530.7 4565.4 3857.1 3866.7 3873.0 3883.6 3894.9 5521.6 5637.6 6147.8 6149.4 6213.3 4714.4 4975.8 5101.6 5388.5 5425.5 5712.6 5867.6 6724.5 6726.9 6762.8 3918.2 4369.0 4371.1 4372.8 4389.2 5334.4 6294.1 6801.6 7087.4 7236.4 4120.4 4171.8 4362.8 4417.5 4432.9 3830.8 4510.8 4519.8 4551.2 4552.3 4445.6 5240.9 5332.6 5366.7 5372.0 6291.8 6726.2 6728.7 6740.3 6743.4 1365.0 1414.9 1465.5 1537.9 1542.1 976.2 1002.5 1009.7 1013.8 1020.4 5213.8 5290.0 5313.9 6349.5 6379.7 6446.4 6726.3 7019.9 7035.8 7105.5 5237.5 6279.3 6292.0 6306.6 6324.3 3642.9 3649.6 3651.4 3673.9 3675.7 6205.3 7174.6 7205.6 7244.4 7258.1 5948.2 6988.4 7127.9 7217.7 7241.5 7148.9 7219.6 7228.0 7253.3 7271.6 5956.4 6866.8 6951.7 7003.2 7067.9 6841.5 6885.1 6886.8 6937.5 6939.8 6718.9 6721.8 6884.9 7250.3 7262.5 4237.9 4240.9 4246.7 4986.1 4995.5 6318.1 7036.8 7069.8 7072.4 7080.6 5857.5 6717.4 6741.1 6758.5 6761.5 6724.3 6741.4 6750.6 6780.6 6786.2 6522.6 6568.8 6600.5 6634.5 6667.9 5859.2 6431.0 6751.7 6783.8 6801.6 5357.7 6190.4 6575.4 6622.0 6626.2 15:05:39 INFO - PID 1652 | 15:05:39 INFO - PID 1652 | [#5] yui.html Cycles:180 Average:25526.94 Median:17835.78 stddev:39773.10 (223.0%) stddev-sans-first:39841.46 15:05:39 INFO - PID 1652 | Values: 833.6 844.8 848.6 862.2 875.6 14942.1 15483.2 17094.4 17544.5 17576.9 16556.9 17434.0 18515.3 18638.7 18664.6 980.1 988.8 1001.2 1002.3 1004.7 1008.9 1010.7 1011.9 1014.2 1016.1 938.6 1041.4 1045.0 1045.1 1046.6 1038.8 1041.2 1041.8 1041.9 1045.2 748.9 752.7 759.0 759.2 765.9 17227.6 17479.9 17571.6 17673.3 17675.2 17410.8 17591.9 17819.0 17840.8 17885.1 14651.1 14781.0 14791.2 14951.8 14984.9 3561.9 3602.5 3627.8 3639.7 3665.9 131617.3 148954.0 149306.0 150211.2 150786.2 13715.9 13903.2 14036.0 14118.0 14216.3 22015.0 22398.9 26675.5 27339.5 27381.9 6826.6 7059.6 7644.6 7969.7 7997.1 3940.4 4041.9 4059.7 4068.3 4081.0 4350.9 4365.3 4432.8 4437.1 4446.6 944.8 949.8 952.9 966.9 967.5 638.6 666.2 676.3 677.7 693.4 9922.0 11899.6 12385.5 12649.4 12681.3 648.7 649.6 653.9 662.5 677.2 4950.7 5014.7 5048.1 5051.7 5178.0 147545.5 148296.0 148846.3 152492.7 153686.9 117816.0 120701.4 129317.0 130312.3 130901.7 97673.5 100645.5 101773.0 104951.0 105085.0 9805.9 11532.5 12038.9 12237.9 12905.9 38998.8 39047.8 43468.1 44156.0 44241.0 58407.7 58607.4 58890.9 59066.5 59560.5 17447.6 17551.8 17718.1 17780.4 17862.0 17952.2 18293.9 19139.7 19177.8 19204.9 17210.5 17610.3 17978.7 18316.8 18399.7 17370.4 17830.7 17955.6 17981.0 18009.2 16769.7 16879.2 16942.0 17711.1 17770.9 9226.9 11896.0 11955.5 12036.8 12678.4 11108.6 13116.8 13309.3 13866.9 13878.6 15:05:39 INFO - PID 1652 | -------- Summary: end -------- 15:05:39 INFO - PID 1652 | 15:05:39 INFO - TEST-INFO | 1652: exit 0 15:05:39 INFO - TEST-OK | dromaeo_css | took 1143516ms 15:05:39 INFO - TEST-START | kraken 15:05:39 INFO - operating with platform_type : mac_ 15:05:39 INFO - Initialising browser for kraken test... 15:05:39 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmpTyujrK/profile 15:05:39 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 15:06:13 INFO - TEST-INFO | 1661: killed by SIGTERM 15:06:13 INFO - Browser initialized. 15:06:13 INFO - Running cycle 1/1 for kraken test... 15:06:13 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmpTyujrK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:06:15 INFO - PID 1665 | 2017-05-30 15:06:15.687 plugin-container[1666:10603] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4743, name = 'com.apple.tsm.portname' 15:06:15 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:06:15 INFO - PID 1665 | 2017-05-30 15:06:15.687 plugin-container[1666:10603] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e03, name = 'com.apple.CFPasteboardClient' 15:06:15 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:06:15 INFO - PID 1665 | 2017-05-30 15:06:15.687 plugin-container[1666:10603] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:06:16 INFO - PID 1665 | 2017-05-30 15:06:16.463 plugin-container[1667:10645] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4943, name = 'com.apple.tsm.portname' 15:06:16 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:06:16 INFO - PID 1665 | 2017-05-30 15:06:16.463 plugin-container[1667:10645] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 15:06:16 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:06:16 INFO - PID 1665 | 2017-05-30 15:06:16.463 plugin-container[1667:10645] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:06:25 INFO - PID 1665 | RSS: Main: 261857280 15:06:25 INFO - PID 1665 | 15:06:28 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 15:06:29 INFO - PID 1665 | RSS: Main: 272207872 15:06:29 INFO - PID 1665 | 15:06:32 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 15:06:32 INFO - PID 1665 | RSS: Main: 280190976 15:06:32 INFO - PID 1665 | 15:06:36 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 15:06:36 INFO - PID 1665 | RSS: Main: 281989120 15:06:36 INFO - PID 1665 | 15:06:38 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 15:06:39 INFO - PID 1665 | RSS: Main: 284213248 15:06:39 INFO - PID 1665 | 15:06:41 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:06:41 INFO - PID 1665 | RSS: Main: 284762112 15:06:41 INFO - PID 1665 | 15:06:44 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 15:06:45 INFO - PID 1665 | RSS: Main: 288985088 15:06:45 INFO - PID 1665 | 15:06:48 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 15:06:49 INFO - PID 1665 | RSS: Main: 292470784 15:06:49 INFO - PID 1665 | 15:06:52 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 15:06:53 INFO - PID 1665 | RSS: Main: 291389440 15:06:53 INFO - PID 1665 | 15:06:54 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:06:55 INFO - PID 1665 | RSS: Main: 291205120 15:06:55 INFO - PID 1665 | 15:06:56 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:06:57 INFO - PID 1665 | RSS: Main: 290873344 15:06:57 INFO - PID 1665 | 15:06:59 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:07:00 INFO - PID 1665 | RSS: Main: 290725888 15:07:00 INFO - PID 1665 | 15:07:03 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:07:04 INFO - PID 1665 | RSS: Main: 290549760 15:07:04 INFO - PID 1665 | 15:07:07 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:07:07 INFO - PID 1665 | RSS: Main: 290525184 15:07:07 INFO - PID 1665 | 15:07:09 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 15:07:09 INFO - PID 1665 | RSS: Main: 292405248 15:07:09 INFO - PID 1665 | 15:07:09 INFO - PID 1665 | __start_tp_report 15:07:09 INFO - PID 1665 | _x_x_mozilla_page_load 15:07:09 INFO - PID 1665 | _x_x_mozilla_page_load_details 15:07:09 INFO - PID 1665 | |i|pagename|runs| 15:07:09 INFO - PID 1665 | |0;ai-astar;99;97;144;119;93;131;168;169;119;115 15:07:09 INFO - PID 1665 | |1;audio-beat-detection;142;113;113;147;111;110;112;116;147;144 15:07:09 INFO - PID 1665 | |2;audio-dft;196;204;206;227;215;225;200;203;201;200 15:07:09 INFO - PID 1665 | |3;audio-fft;80;58;56;93;48;53;76;87;90;82 15:07:09 INFO - PID 1665 | |4;audio-oscillator;83;69;83;71;69;53;93;102;104;76 15:07:09 INFO - PID 1665 | |5;imaging-gaussian-blur;91;91;90;90;92;90;96;91;91;90 15:07:09 INFO - PID 1665 | |6;imaging-darkroom;106;155;128;98;122;99;97;97;100;99 15:07:09 INFO - PID 1665 | |7;imaging-desaturate;145;120;91;93;90;90;91;89;90;92 15:07:09 INFO - PID 1665 | |8;json-parse-financial;50;50;52;51;52;41;43;59;60;80 15:07:09 INFO - PID 1665 | |9;json-stringify-tinderbox;56;54;65;69;61;57;62;84;71;57 15:07:09 INFO - PID 1665 | |10;stanford-crypto-aes;86;85;88;89;89;87;86;96;130;91 15:07:09 INFO - PID 1665 | |11;stanford-crypto-ccm;140;142;137;134;136;139;142;157;151;153 15:07:09 INFO - PID 1665 | |12;stanford-crypto-pbkdf2;184;186;185;186;185;234;232;180;186;201 15:07:09 INFO - PID 1665 | |13;stanford-crypto-sha256-iterative;67;69;67;66;87;95;99;99;98;73 15:07:09 INFO - PID 1665 | __end_tp_report 15:07:09 INFO - PID 1665 | __start_cc_report 15:07:09 INFO - PID 1665 | _x_x_mozilla_cycle_collect,1181 15:07:09 INFO - PID 1665 | __end_cc_report 15:07:09 INFO - PID 1665 | __startTimestamp1496182029904__endTimestamp 15:07:09 INFO - PID 1665 | 15:07:09 INFO - PID 1665 | ------- Summary: start ------- 15:07:09 INFO - PID 1665 | Number of tests: 14 15:07:09 INFO - PID 1665 | 15:07:09 INFO - PID 1665 | [#0] ai-astar Cycles:10 Average:125.40 Median:168.50 stddev:27.56 (16.4%) stddev-sans-first:27.53 15:07:09 INFO - PID 1665 | Values: 99.0 97.0 144.0 119.0 93.0 131.0 168.0 169.0 119.0 115.0 15:07:09 INFO - PID 1665 | 15:07:09 INFO - PID 1665 | [#1] audio-beat-detection Cycles:10 Average:125.50 Median:129.00 stddev:16.91 (13.1%) stddev-sans-first:16.85 15:07:09 INFO - PID 1665 | Values: 142.0 113.0 113.0 147.0 111.0 110.0 112.0 116.0 147.0 144.0 15:07:09 INFO - PID 1665 | 15:07:09 INFO - PID 1665 | [#2] audio-dft Cycles:10 Average:207.70 Median:205.00 stddev:10.87 (5.3%) stddev-sans-first:10.68 15:07:09 INFO - PID 1665 | Values: 196.0 204.0 206.0 227.0 215.0 225.0 200.0 203.0 201.0 200.0 15:07:09 INFO - PID 1665 | 15:07:09 INFO - PID 1665 | [#3] audio-fft Cycles:10 Average:72.30 Median:81.00 stddev:16.86 (20.8%) stddev-sans-first:17.65 15:07:09 INFO - PID 1665 | Values: 80.0 58.0 56.0 93.0 48.0 53.0 76.0 87.0 90.0 82.0 15:07:09 INFO - PID 1665 | 15:07:09 INFO - PID 1665 | [#4] audio-oscillator Cycles:10 Average:80.30 Median:73.50 stddev:16.04 (21.8%) stddev-sans-first:16.98 15:07:09 INFO - PID 1665 | Values: 83.0 69.0 83.0 71.0 69.0 53.0 93.0 102.0 104.0 76.0 15:07:09 INFO - PID 1665 | 15:07:09 INFO - PID 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:91.20 Median:91.00 stddev:1.81 (2.0%) stddev-sans-first:1.92 15:07:09 INFO - PID 1665 | Values: 91.0 91.0 90.0 90.0 92.0 90.0 96.0 91.0 91.0 90.0 15:07:09 INFO - PID 1665 | 15:07:09 INFO - PID 1665 | [#6] imaging-darkroom Cycles:10 Average:110.10 Median:97.00 stddev:19.24 (19.8%) stddev-sans-first:20.35 15:07:09 INFO - PID 1665 | Values: 106.0 155.0 128.0 98.0 122.0 99.0 97.0 97.0 100.0 99.0 15:07:09 INFO - PID 1665 | 15:07:09 INFO - PID 1665 | [#7] imaging-desaturate Cycles:10 Average:99.10 Median:90.50 stddev:18.60 (20.6%) stddev-sans-first:9.82 15:07:09 INFO - PID 1665 | Values: 145.0 120.0 91.0 93.0 90.0 90.0 91.0 89.0 90.0 92.0 15:07:09 INFO - PID 1665 | 15:07:09 INFO - PID 1665 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:52.00 stddev:10.93 (21.0%) stddev-sans-first:11.51 15:07:09 INFO - PID 1665 | Values: 50.0 50.0 52.0 51.0 52.0 41.0 43.0 59.0 60.0 80.0 15:07:09 INFO - PID 1665 | 15:07:09 INFO - PID 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:63.60 Median:63.50 stddev:9.12 (14.4%) stddev-sans-first:9.25 15:07:09 INFO - PID 1665 | Values: 56.0 54.0 65.0 69.0 61.0 57.0 62.0 84.0 71.0 57.0 15:07:09 INFO - PID 1665 | 15:07:09 INFO - PID 1665 | [#10] stanford-crypto-aes Cycles:10 Average:92.70 Median:88.50 stddev:13.48 (15.2%) stddev-sans-first:14.08 15:07:09 INFO - PID 1665 | Values: 86.0 85.0 88.0 89.0 89.0 87.0 86.0 96.0 130.0 91.0 15:07:09 INFO - PID 1665 | 15:07:09 INFO - PID 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:143.10 Median:142.00 stddev:7.84 (5.5%) stddev-sans-first:8.23 15:07:09 INFO - PID 1665 | Values: 140.0 142.0 137.0 134.0 136.0 139.0 142.0 157.0 151.0 153.0 15:07:09 INFO - PID 1665 | 15:07:09 INFO - PID 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.90 Median:186.00 stddev:20.29 (10.9%) stddev-sans-first:21.06 15:07:09 INFO - PID 1665 | Values: 184.0 186.0 185.0 186.0 185.0 234.0 232.0 180.0 186.0 201.0 15:07:09 INFO - PID 1665 | 15:07:09 INFO - PID 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:82.00 Median:91.00 stddev:14.85 (16.3%) stddev-sans-first:14.72 15:07:09 INFO - PID 1665 | Values: 67.0 69.0 67.0 66.0 87.0 95.0 99.0 99.0 98.0 73.0 15:07:09 INFO - PID 1665 | -------- Summary: end -------- 15:07:09 INFO - PID 1665 | 15:07:10 INFO - TEST-INFO | 1665: exit 0 15:07:10 INFO - TEST-OK | kraken | took 90834ms 15:07:10 INFO - SUITE-END | took 1234s 15:07:11 INFO - Completed test suite (00:20:34) 15:07:11 INFO - javascript benchmark 15:07:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7985.150441647744, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3549.946750798738, 3555.128993396618, 3577.141486043347, 3577.8747743828962, 3596.7122630189588, 4840.636952228584, 6051.0, 6137.202163718716, 6180.134781130645, 6202.1006953991755, 6060.151578778975, 6129.4789942854795, 6139.6623185724875, 6156.938430615693, 6219.689015549227, 4378.97810510947, 4607.953920460786, 5278.81524146653, 5298.09932311508, 5395.595330350229, 4900.117978763821, 5395.73021348933, 5487.259220005308, 5487.75305111271, 5497.285352904117, 5316.4683531646915, 5349.5720342372515, 5380.542653874425, 5409.43200963901, 5437.157240627709, 4841.789552611847, 5113.463086375914, 5372.140457526777, 5388.622796404214, 5423.322084739408, 5297.682139071668, 5342.0651386007685, 5359.785608575652, 5365.946340536622, 5375.569954403638, 5819.563532735062, 6034.0948857671265, 6157.4458298753325, 6190.628562286232, 6195.287541932646, 6424.38968298011, 6452.483801295885, 6527.379898909596, 6533.869322613568, 6551.05009773585, 6485.967570162166, 6638.17022872141, 6656.101426307435, 6683.298253683391, 6688.0, 6128.3565225651555, 6266.749330026793, 6289.496840252768, 6303.0, 6326.68366581669, 5571.888562228733, 6584.9012264816065, 6695.296993815677, 6802.761903333359, 6812.386885180357, 6301.495880329563, 6336.778212762531, 6396.456301214356, 6411.743530258737, 6480.060391243243, 6530.444912182423, 6648.96675516629, 6655.900161497581, 6659.4006539411685, 6676.86646267072, 6536.444402225864, 6564.146660934143, 6565.24499682533, 6580.374864387875, 6588.571742836701, 6044.153818465418, 6419.839504012343, 6450.709718062699, 6499.772507962199, 6535.477161827042, 5254.7898084076205, 5256.001359741714, 5268.710220937808, 5286.20706893969, 5297.523222909879, 4700.882477938079, 4704.059188162313, 4708.340832283483, 4717.3159891816185, 4728.1962066448095, 4826.420829500412, 6230.750769969241, 6240.875182496325, 6282.0, 6294.590851594632, 6630.0, 6662.200535935622, 6681.064650948871, 6686.231083425469, 6688.197416310074, 6251.562390632703, 6311.684415779285, 6328.082428047999, 6334.493240540745, 6342.778002769973, 4511.684182107221, 5676.460736230051, 5807.12893066035, 5813.709314534256, 5819.854503637358, 6817.147856517935, 6846.4522838173825, 6860.5540639858245, 6864.0, 6865.622390768555, 6674.899876501953, 6680.064790929371, 6682.3317668232785, 6700.731970721116, 6705.530612857053, 6553.475721942328, 6648.7340506379205, 6765.560238584476, 6784.3215678433735, 6856.314368563104, 6433.613983161025, 6500.0, 6520.119783829123, 6526.151600291932, 6627.900581491185, 6652.900206497004, 6674.799756007327, 6678.23200331968, 6682.866342673022, 6689.431398331197, 6290.559660823698, 6343.841403964753, 6372.553921225655, 6425.646589437626, 6442.0, 6408.519361047846, 6422.775202867877, 6436.903446448214, 6448.838779030561, 6454.1609590752905, 6208.50331973432, 6279.340669229665, 6352.110704501281, 6367.554271201157, 6422.9036564450635, 6234.625922444668, 6345.333739957238, 6374.394432528902, 6439.291677915332, 6467.838304042437, 6286.6856657166045, 6324.525660575383, 6327.493800496041, 6331.145295385064, 6338.271098823694], "value": 5999.155844185519, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [774.4810976645647, 821.080389963241, 840.504102579478, 1250.712336162682, 1322.7685155097868, 38666.0, 39234.68418473966, 39263.0184245394, 39278.072192780746, 39286.21427571447, 39881.800590997074, 40338.99152521188, 40380.59619403805, 40397.596024039754, 40399.19201615966, 7852.254035866584, 8361.749147525554, 8565.48607083577, 8581.399302048829, 8590.281943611302, 8468.703595374163, 8591.828163436727, 8598.011228708685, 8598.097199794025, 8604.31165506758, 8560.700375486858, 8566.100559441262, 8597.18326758957, 8603.827923441528, 8616.310695144373, 8562.400631955765, 8589.398742088057, 8589.742307730778, 8596.742097737078, 8616.26761725254, 8321.292690121287, 8381.0, 8386.790330241733, 8390.706325278587, 8392.538410387426, 37308.88073357804, 37526.43710344347, 37571.248575028345, 37702.43446348307, 37803.24393512114, 3498.1079824644808, 3522.7710198836994, 3526.3123690880034, 3526.312369088029, 3533.293341331744, 30995.845020774977, 31034.844825775952, 31040.0, 31055.22361940947, 31126.844365778026, 2842.2325971987448, 2953.6708481183555, 3037.271054946819, 3138.3252600690735, 3152.3064925716308, 251824.0, 255320.0, 272442.0, 273677.0, 282740.0, 47885.0, 48199.5180048195, 48274.03451931012, 48426.5157348422, 48450.0, 50753.746231268604, 50809.0, 50924.49075509272, 50937.0, 50945.23582146271, 14043.227622480657, 14131.222782746841, 14219.786703199461, 14247.358868850926, 14251.78622320666, 11527.250728702607, 11652.41737913101, 11679.474423650956, 11690.941545292219, 11730.413479326, 11370.317780933005, 13239.073264871558, 13593.728125437634, 13627.114237574526, 13664.248466334448, 5208.427073021964, 5835.737391817293, 5943.61366511184, 5986.161937328778, 6026.758929642853, 747.7196051480695, 858.3862538285156, 873.1835733589114, 899.3614533681028, 923.2429407885469, 21674.89162554177, 22984.015625622927, 23428.882855585955, 24158.75841241565, 24557.38606534851, 1113.204059097749, 1119.7200699825044, 1144.210494758614, 1154.1747650429984, 1157.6064138193055, 11792.941035294769, 12209.145359824726, 12343.197692150161, 12402.5659101931, 12418.75162496745, 322961.0, 324125.0, 324442.0, 324767.0, 324916.0, 54600.45399546033, 54967.0, 54981.0, 55152.172717409274, 55179.0, 1326.602019394178, 1489.9180545070126, 1514.833368329483, 1535.708215439063, 1539.9538013859378, 19623.9394863543, 22021.449463763533, 23478.295651130495, 23748.406289842893, 23795.524089518458, 38310.0, 38335.42496862605, 38370.61629383782, 38394.616053839105, 38478.2304353917, 275404.0, 277569.0, 278504.0, 278636.0, 279573.0, 27785.44429111447, 28197.295067623472, 31308.0, 34577.962661120204, 34599.481007785405, 39165.41251881281, 40633.39049914195, 41313.0, 41447.171056579304, 41484.0, 28640.85679571589, 29556.556651650673, 29593.85203073971, 29620.703792961795, 29734.702652974058, 28784.992525261514, 28813.56779648265, 29110.417791643624, 29396.265093372836, 29531.852340738158, 34627.0, 34870.47694284537, 35037.649623503436, 35179.472307915916, 35188.64811351854, 27941.58087628728, 28736.0, 29101.563476547446, 29165.708342917147, 29749.851250743606, 18709.81290187135, 23201.767982320634, 23531.0, 23540.293791185606, 23561.29316120519], "value": 17228.033076346335, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.89951026840475, 430.09465076015005, 435.4339358833516, 442.3144126603763, 454.34574213133084, 21998.0, 22800.42998925028, 22887.656685149715, 23324.067037318528, 24591.13931012415, 19511.0, 20871.0, 23673.644895326583, 24662.89016994235, 24702.629460558106, 6157.230346206713, 6282.371762823726, 6310.495160387158, 6312.5896816707, 6324.968375158118, 5191.325127733408, 6253.749850005995, 6284.12022316876, 6296.307406185316, 6317.084022816689, 5228.503292187236, 5879.52963762898, 6132.018876979684, 6180.443760061594, 6188.133661287423, 5826.82519524415, 6068.514518838482, 6134.04922237054, 6135.846603834895, 6147.569670123094, 5419.5393391561765, 5458.563314934796, 5465.562754979592, 5472.26124473195, 5478.096114141162, 28822.711772882212, 29216.269593260127, 29606.407871842657, 29682.406351873058, 29700.40599188004, 2124.702541644171, 2202.0, 2202.7246594175726, 2204.0, 2206.911723531057, 18947.715784263248, 19182.232710691555, 19185.944773037478, 19234.71147932782, 19289.807101928942, 2099.5695882344085, 2152.4834039830484, 2181.7054697615777, 2183.159483598826, 2208.8012078912975, 170327.29672703237, 178676.0, 184799.0, 186066.0, 196132.0386796128, 12479.064070194772, 12763.0, 12766.744665106831, 12815.551455699006, 12844.100912936192, 16461.25924333408, 16592.668146637, 16604.83395166057, 16631.91684041596, 16635.0, 7607.619619019027, 8946.642134314685, 9137.862932056025, 9154.908450915405, 9188.127127922837, 8612.18184272493, 8612.827743445221, 8750.781230469162, 8847.823043539092, 8850.20348168655, 7198.064251647253, 8190.795230119175, 8238.876416853753, 8290.917090829134, 8309.04445988719, 2011.7787043425212, 2039.3881835449306, 2202.119152339077, 2206.018321846795, 2325.3141472432353, 930.4091901642506, 969.9563519641633, 974.8050389922043, 978.9804203915882, 1016.7407311135613, 21455.034523446484, 22005.11979520833, 22014.88992555027, 22050.88974555149, 22266.666000010013, 862.7411776467035, 961.744922875654, 1007.4660429972126, 1014.9746256343503, 1037.0821822686976, 10556.736081598021, 10715.051431249676, 10950.452477376259, 11142.331460112418, 11318.26431281964, 497786.0, 499253.0, 505588.0, 510374.0, 562599.0, 122954.77045229611, 144730.0, 153269.0, 153554.0, 155159.4484055145, 1022.5194158745378, 1054.8734151901667, 1154.2497376705041, 1168.5092261250295, 1238.2013601227159, 18333.08334583292, 19725.21099156075, 21116.788832112095, 23272.185473508347, 23366.065357385512, 51829.7408512955, 66478.0, 66872.0, 67271.66364168147, 68219.0, 59594.0, 68354.50632189935, 69049.61900762057, 87319.1268087311, 89347.10652893648, 10111.7472063199, 10210.132138768287, 10244.231682623684, 10252.846207307046, 10268.229882758673, 15334.696550792862, 16742.82800203974, 17030.0, 18314.908425457787, 18435.98602076845, 13260.336983150612, 14626.268686565416, 14755.55733328002, 15208.391664333303, 15302.464413745465, 13319.0, 13708.45166193381, 13715.657108572366, 13789.0, 13987.930060349634, 13908.304584770518, 15120.319585618894, 15266.236688165325, 15290.61773455628, 15547.611309717347, 9143.679971201242, 9347.915187987812, 10078.697639070839, 10165.288429810138, 10179.440130792878, 14540.200288984208, 14590.927045364706, 14599.562013139623, 14737.557873263819, 14773.704525909636], "value": 11041.12682050735, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1354.4988354308907, 1362.8705272999068, 1366.5968539280923, 1461.8611231932962, 1499.2278976327186, 11649.767004659901, 11697.883021169786, 12139.271643701384, 12169.817452738205, 12451.0, 11850.229735067214, 12005.75988480232, 12074.275543467398, 12076.577319793809, 12129.029677625791, 2526.191618682018, 2557.105013245368, 2580.380708629925, 2582.6384306197056, 2584.082650659017, 2517.874106294687, 2538.8984440622353, 2555.399481121935, 2560.2703229579574, 2560.2703229579574, 2272.3864556569715, 2350.095213342858, 2516.766784275701, 2525.0404846158435, 2525.722170561246, 2042.805933436321, 2482.2429159106373, 2504.0484615846044, 2505.8621775802326, 2507.736687647798, 1995.7605087389607, 2280.6579013147993, 2468.827182097254, 2495.6880389951257, 2520.596704527267, 11766.647000589996, 11840.348780817052, 11841.940790295994, 11860.703482412922, 11902.88097119035, 6443.742250309982, 6701.229358623772, 6708.563943343667, 6709.899351509731, 6728.596284222962, 4097.364908439196, 4269.6370808481315, 4310.418093557361, 4317.892052698676, 4321.719088259253, 1223.2843786384888, 1223.5044806853239, 1224.179799534314, 1245.64499117751, 1248.7752204603169, 15428.3828646854, 15876.206189690469, 16440.75338869918, 16533.594644455236, 16606.66786664272, 5504.6421982571, 5516.14499752539, 5553.611247212656, 5680.147977803363, 5696.174054762119, 12064.698382540506, 12101.878981210251, 12137.271763694207, 12148.0, 12157.148999569945, 6450.903236451457, 6472.126262954535, 6556.704948277244, 6560.9343906560325, 6562.934370656328, 6050.455459008623, 6126.326104128542, 6176.197094377702, 6186.1648677427975, 6205.844853878689, 4319.29387891781, 4417.3815665806815, 4442.155990361821, 4462.665300102505, 4467.955320446754, 1247.3015111537386, 1261.306281545165, 1265.9620211393674, 1279.7376537809728, 1283.6919139406475, 731.7987553422852, 766.6933226709361, 791.5092642561649, 800.3517151107621, 800.5036877136097, 6181.289151747606, 6729.3270672933295, 6818.454523638097, 7022.683979220948, 7091.9290807091265, 898.9955050224708, 953.6614501851882, 956.3783540698463, 958.8226178156924, 965.0494263150791, 3579.7533304384015, 3878.26313000529, 3916.118873253495, 3962.445257663929, 4064.4716186895516, 13822.93088534551, 15291.311890964711, 15351.232438378036, 15359.539213823604, 15367.308471118826, 15005.399784008736, 15069.47256846005, 15137.697246055017, 15258.542243732705, 15259.694806103817, 8090.705487121914, 8112.797180070427, 8565.528895910784, 8600.741977740678, 8700.911781172474, 5496.367917689517, 6952.478564107611, 7141.928580714126, 7177.138743350832, 7267.600281984542, 12801.487940482277, 12880.871191287968, 13086.280254586089, 13145.408456619667, 13248.536301229411, 12910.870891290966, 12926.482940682268, 13173.93413032967, 13274.203547787163, 13471.326433678472, 8442.788930276793, 8572.52851093196, 8923.776905577413, 9566.234701224024, 9600.567974441026, 8781.385303028726, 10087.848682269625, 10653.520591573553, 10684.57261709523, 10804.567817287221, 9142.49716265585, 9268.397554158959, 9283.860742088738, 9302.116298951589, 9327.86008209891, 9011.819763604823, 9125.22435592982, 9149.451032938045, 9191.586378612845, 9231.446113233227, 8161.102278749451, 8233.300169485421, 8331.666733330841, 8338.958305208678, 8350.87473687883, 5856.267966504187, 5869.647821130746, 5954.553408494294, 5968.134620480093, 6088.665123418255, 5948.078047902582, 6082.513398928164, 6115.235595550556, 6124.601900876429, 6241.469475094668], "value": 5370.684894429749, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.2287311012424, 389.3879339534328, 390.0093761844913, 401.6444499813131, 402.27389561840903, 5291.206319052135, 6305.6216627002295, 6400.711967961442, 6462.709178086986, 6469.320721324258, 5662.546996240301, 5938.495227905622, 6100.871338802313, 6181.536384771149, 6269.811905642838, 3636.5454318210222, 3857.9807100964454, 3952.604739526046, 4574.565416285464, 4576.771161441931, 4014.85576610666, 4123.484564429446, 4546.931796023062, 4593.724376537401, 4598.195315819735, 4526.207913615121, 4532.161550113223, 4536.614387777026, 4541.954580454203, 4549.909001819961, 3809.3524100902914, 3991.2017596480528, 4283.528811830696, 4530.682852200331, 4565.3836732041245, 3857.112864041258, 3866.7486613370043, 3872.961270387288, 3883.611638836094, 3894.883153505399, 5521.641093328961, 5637.63355381903, 6147.754089836401, 6149.41580549847, 6213.328268844395, 4714.387129673155, 4975.825846095369, 5101.632922271283, 5388.46115388458, 5425.4808653577065, 5712.571557133231, 5867.618604790675, 6724.462043036546, 6726.93273067268, 6762.8309292267595, 3918.2172111689115, 4368.978155109236, 4371.14762621289, 4372.781360931941, 4389.18800021997, 5334.3598768147685, 6294.055891616248, 6801.591904485697, 7087.397571206454, 7236.384907282887, 4120.423140760296, 4171.812268447927, 4362.8036738346855, 4417.491988421309, 4432.911341773211, 3830.7701537907815, 4510.819567217274, 4519.751413672279, 4551.249043907718, 4552.294394368878, 4445.555444455593, 5240.895182096413, 5332.573678976057, 5366.731663416814, 5371.973140134352, 6291.811245662638, 6726.150308665156, 6728.697208625624, 6740.325967403221, 6743.393094621508, 1364.979525307121, 1414.8514405987428, 1465.4577806211769, 1537.9000364976241, 1542.105578764314, 976.1848856205148, 1002.5338217728792, 1009.6668099527138, 1013.8377859542438, 1020.3877673396051, 5213.843584692389, 5290.047791397508, 5313.884084342331, 6349.52378571609, 6379.681015949184, 6446.419822215929, 6726.293739157416, 7019.876819708924, 7035.788926332219, 7105.538140020985, 5237.476252374732, 6279.340669229757, 6292.0, 6306.558540902184, 6324.3043265240785, 3642.8724994625063, 3649.5949059611708, 3651.434027725707, 3673.9081522962138, 3675.650813172744, 6205.317415084337, 7174.641267936582, 7205.603691797001, 7244.4062306292535, 7258.129024516986, 5948.196993405922, 6988.44092472601, 7127.89308160378, 7217.711291548279, 7241.456890733216, 7148.892766608505, 7219.639018049182, 7227.9638601806655, 7253.274672532704, 7271.563706177646, 5956.374580668968, 6866.828329291808, 6951.721931122698, 7003.194632617314, 7067.893981590383, 6841.486888483483, 6885.139357580302, 6886.793396198122, 6937.479689023445, 6939.757108501179, 6718.93281067183, 6721.798346049626, 6884.8967265490055, 7250.3112204340505, 7262.527935684164, 4237.855778939607, 4240.915181696287, 4246.745195288293, 4986.12742770021, 4995.47547507521, 6318.115463834974, 7036.753713619999, 7069.787906362817, 7072.3634872861685, 7080.575165490087, 5857.502112320501, 6717.361850624183, 6741.11269329732, 6758.4931130166415, 6761.526693131434, 6724.327567243236, 6741.393274605309, 6750.594964302158, 6780.593164410151, 6786.219584747817, 6522.6412547308455, 6568.784774816674, 6600.471962242913, 6634.469242460688, 6667.933320666732, 5859.179714839841, 6430.971044632836, 6751.729930802713, 6783.762568310085, 6801.557898736566, 5357.740930881162, 6190.442860142609, 6575.4410875076155, 6621.966890165519, 6626.204855417381], "value": 4817.124968134405, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [833.5707110837923, 844.8479273730729, 848.6053984897023, 862.1766213266327, 875.6147295190116, 14942.103473791552, 15483.225838708076, 17094.40169594063, 17544.473665790014, 17576.912115439438, 16556.917215413938, 17434.0, 18515.2593896244, 18638.720419193724, 18664.626707465843, 980.0640388429068, 988.7676396046924, 1001.1990407673868, 1002.3334482569082, 1004.6634377483551, 1008.8789345278577, 1010.7220514358537, 1011.8869243831763, 1014.1683819268203, 1016.1261315268863, 938.5964035464748, 1041.4428280869736, 1045.0333441566559, 1045.142982754142, 1046.5656752447726, 1038.8285932821075, 1041.2190856857394, 1041.7551875309296, 1041.9218558608134, 1045.2056437107776, 748.9101307843039, 752.7403045949138, 759.0436050576259, 759.1762937213131, 765.873630850909, 17227.569310767205, 17479.863808852384, 17571.5607109822, 17673.293068277253, 17675.20461579232, 17410.825891741173, 17591.912040439714, 17819.0, 17840.821591784046, 17885.105744712844, 14651.120932743963, 14781.0, 14791.186484743335, 14951.775723364159, 14984.925075374662, 3561.928761424783, 3602.4596310553366, 3627.836747346376, 3639.727020473475, 3665.9266814663824, 131617.34191328983, 148954.0, 149306.0, 150211.24894375567, 150786.24606877004, 13715.862841371658, 13903.165810051429, 14036.0, 14118.0, 14216.289185540682, 22015.0, 22398.888005559867, 26675.466490670467, 27339.496327702167, 27381.86309068442, 6826.590404575742, 7059.647017649097, 7644.617769111522, 7969.681212751425, 7997.120316765151, 3940.4286378474962, 4041.8787436376956, 4059.6752259819145, 4068.2880495913096, 4080.959190408117, 4350.934735978963, 4365.257906155961, 4432.778361081933, 4437.134758722018, 4446.644268458515, 944.7779771753633, 949.781550243434, 952.876126103616, 966.8791401074866, 967.5452537307455, 638.6232123047424, 666.2171947961126, 676.2898956096079, 677.7153595489906, 693.3586432549871, 9921.950390248003, 11899.643010709693, 12385.504579816887, 12649.367531623566, 12681.30252836103, 648.7275344355382, 649.5647915896361, 653.8855700252532, 662.4634046422414, 677.204284965164, 4950.727709975986, 5014.749262536931, 5048.116579598555, 5051.6716413433005, 5177.974110129425, 147545.52454475532, 148296.0, 148846.25576872047, 152492.71260931095, 153686.86944323787, 117816.0, 120701.39649301698, 129317.0, 130312.3484382572, 130901.69098308895, 97673.51163244281, 100645.49031764676, 101773.0, 104951.0, 105085.0, 9805.901940980784, 11532.538698451968, 12038.939805300917, 12237.938810305892, 12905.935470322589, 38998.830035098996, 39047.80476097715, 43468.13063738644, 44156.0, 44241.0, 58407.70796145992, 58607.4139258619, 58890.93881714119, 59066.45601263938, 59560.51098722393, 17447.56381090432, 17551.82448175502, 17718.134198054377, 17780.3776867809, 17862.0, 17952.19215135296, 18293.908530457797, 19139.712904306725, 19177.808221917603, 19204.903975480032, 17210.483685489457, 17610.295588176843, 17978.730319044964, 18316.81683183151, 18399.72400413968, 17370.39203627867, 17830.732539012184, 17955.551111222325, 17981.0, 18009.189586468903, 16769.74845377345, 16879.240434180738, 16941.98348099118, 17711.11444427748, 17770.91114544419, 9226.907730922874, 11896.0, 11955.521779128738, 12036.75926481483, 12678.366081696062, 11108.555657773599, 13116.808833403871, 13309.267990260241, 13866.861331386557, 13878.583642490741], "value": 8780.327357886184, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1542.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 97.0, 144.0, 119.0, 93.0, 131.0, 168.0, 169.0, 119.0, 115.0], "value": 125.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 113.0, 113.0, 147.0, 111.0, 110.0, 112.0, 116.0, 147.0, 144.0], "value": 125.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 204.0, 206.0, 227.0, 215.0, 225.0, 200.0, 203.0, 201.0, 200.0], "value": 207.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 56.0, 93.0, 48.0, 53.0, 76.0, 87.0, 90.0, 82.0], "value": 72.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 69.0, 83.0, 71.0, 69.0, 53.0, 93.0, 102.0, 104.0, 76.0], "value": 80.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 90.0, 90.0, 92.0, 90.0, 96.0, 91.0, 91.0, 90.0], "value": 91.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 155.0, 128.0, 98.0, 122.0, 99.0, 97.0, 97.0, 100.0, 99.0], "value": 110.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 120.0, 91.0, 93.0, 90.0, 90.0, 91.0, 89.0, 90.0, 92.0], "value": 99.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 52.0, 51.0, 52.0, 41.0, 43.0, 59.0, 60.0, 80.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 65.0, 69.0, 61.0, 57.0, 62.0, 84.0, 71.0, 57.0], "value": 63.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 88.0, 89.0, 89.0, 87.0, 86.0, 96.0, 130.0, 91.0], "value": 92.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 142.0, 137.0, 134.0, 136.0, 139.0, 142.0, 157.0, 151.0, 153.0], "value": 143.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 185.0, 186.0, 185.0, 234.0, 232.0, 180.0, 186.0, 201.0], "value": 195.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 67.0, 66.0, 87.0, 95.0, 99.0, 99.0, 98.0, 73.0], "value": 82.0, "unit": "score"}]}]} 15:07:11 INFO - Return code: 0 15:07:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:07:11 INFO - # TBPL SUCCESS # 15:07:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:07:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:07:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:07:11 INFO - 2017-05-30 15:07:11.202 screenresolution[1673:11036] starting screenresolution argv=screenresolution get 15:07:11 INFO - 2017-05-30 15:07:11.205 screenresolution[1673:11036] Display 0: 1600x1200x32@60 15:07:11 INFO - 2017-05-30 15:07:11.211 screenresolution[1674:11040] starting screenresolution argv=screenresolution list 15:07:11 INFO - Available Modes on Display 0 15:07:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:07:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:07:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:07:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:07:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:07:11 INFO - Intel Iris: 15:07:11 INFO - Chipset Model: Intel Iris 15:07:11 INFO - Type: GPU 15:07:11 INFO - Bus: Built-In 15:07:11 INFO - VRAM (Dynamic, Max): 1536 MB 15:07:11 INFO - Vendor: Intel (0x8086) 15:07:11 INFO - Device ID: 0x0a2e 15:07:11 INFO - Revision ID: 0x0009 15:07:11 INFO - Displays: 15:07:11 INFO - Display: 15:07:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:07:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:07:11 INFO - Display Serial Number: 1600x1200 60 15:07:11 INFO - Main Display: Yes 15:07:11 INFO - Mirror: Off 15:07:11 INFO - Online: Yes 15:07:11 INFO - Rotation: Supported 15:07:11 INFO - Return code: 0 15:07:11 INFO - Running post-action listener: _resource_record_post_action 15:07:11 INFO - [mozharness: 2017-05-30 22:07:11.447304Z] Finished run-tests step (success) 15:07:11 INFO - Running post-run listener: _resource_record_post_run 15:07:11 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 39676928; Write bytes: 570421760; Read time: 417; Write time: 2296 15:07:11 INFO - TinderboxPrint: CPU usage
53.1% 15:07:11 INFO - TinderboxPrint: I/O read bytes / time
39,676,928 / 417 15:07:11 INFO - TinderboxPrint: I/O write bytes / time
570,421,760 / 2,296 15:07:11 INFO - TinderboxPrint: CPU idle
2,357.3 (46.9%) 15:07:11 INFO - TinderboxPrint: CPU system
1,017.9 (20.2%) 15:07:11 INFO - TinderboxPrint: CPU user
1,654.4 (32.9%) 15:07:11 INFO - TinderboxPrint: Swap in / out
284,430,336 / 0 15:07:11 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 229925376; Write bytes: 231220736; Read time: 16778; Write time: 468 15:07:11 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 15:07:11 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 38677504; Write bytes: 320740352; Read time: 400; Write time: 1783 15:07:11 INFO - Running post-run listener: _upload_blobber_files 15:07:11 INFO - Blob upload gear active. 15:07:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:07:11 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 15:07:11 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'] 15:07:11 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 15:07:12 INFO - (blobuploader) - INFO - Open directory for files ... 15:07:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:07:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:07:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:07:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:07:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:07:13 INFO - (blobuploader) - INFO - Done attempting. 15:07:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:07:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:07:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:07:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:07:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:07:14 INFO - (blobuploader) - INFO - Done attempting. 15:07:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:07:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:07:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:07:15 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:07:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:07:15 INFO - (blobuploader) - INFO - Done attempting. 15:07:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:07:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:07:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:07:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:07:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:07:17 INFO - (blobuploader) - INFO - Done attempting. 15:07:17 INFO - (blobuploader) - INFO - Iteration through files over. 15:07:17 INFO - Return code: 0 15:07:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:07:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:07:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e02fd594d77621ac538d4957e085651bf124c6ba756c10c18f0a026683927c87426760cacd647c16314de83b4a0d04e476df1f737421e70f487adedb1b09c1c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12ba2f6e90cbc8bb4801a57abbf18822324e317b853cc444fa37faac8811ea2431d4137f501bad39d7d43ff281ba5e5f6d432fbbdbeb270388c4e6c868b4c733", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ded1b382d67cbee6ccce75c72472960c13875fb88fafaeafc52d8beee183a81552f85211324ea3120a2835392e5368139c298ed762d71b6fe8a67e26dd8d0cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee1ec9ce6be62704a2bdba377daf65fc60c18e12459f0c82910d3b637043206d635563aa3105d5c55e8c36cdb7da8e0d5bb5f2282e8a57833566cc8a96ffbe47"} 15:07:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:07:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:07:17 INFO - Contents: 15:07:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e02fd594d77621ac538d4957e085651bf124c6ba756c10c18f0a026683927c87426760cacd647c16314de83b4a0d04e476df1f737421e70f487adedb1b09c1c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12ba2f6e90cbc8bb4801a57abbf18822324e317b853cc444fa37faac8811ea2431d4137f501bad39d7d43ff281ba5e5f6d432fbbdbeb270388c4e6c868b4c733", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ded1b382d67cbee6ccce75c72472960c13875fb88fafaeafc52d8beee183a81552f85211324ea3120a2835392e5368139c298ed762d71b6fe8a67e26dd8d0cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee1ec9ce6be62704a2bdba377daf65fc60c18e12459f0c82910d3b637043206d635563aa3105d5c55e8c36cdb7da8e0d5bb5f2282e8a57833566cc8a96ffbe47"} 15:07:17 INFO - Running post-run listener: copy_logs_to_upload_dir 15:07:17 INFO - Copying logs to upload dir... 15:07:17 INFO - mkdir: /builds/slave/test/build/upload/logs 15:07:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.410551 ========= master_lag: -0.16 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-05-30 15:07:17.596424) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 15:07:17.599703) ========= 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.vGdJP8YZLi/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.1S6GguDfGB/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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/4e02fd594d77621ac538d4957e085651bf124c6ba756c10c18f0a026683927c87426760cacd647c16314de83b4a0d04e476df1f737421e70f487adedb1b09c1c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12ba2f6e90cbc8bb4801a57abbf18822324e317b853cc444fa37faac8811ea2431d4137f501bad39d7d43ff281ba5e5f6d432fbbdbeb270388c4e6c868b4c733", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ded1b382d67cbee6ccce75c72472960c13875fb88fafaeafc52d8beee183a81552f85211324ea3120a2835392e5368139c298ed762d71b6fe8a67e26dd8d0cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee1ec9ce6be62704a2bdba377daf65fc60c18e12459f0c82910d3b637043206d635563aa3105d5c55e8c36cdb7da8e0d5bb5f2282e8a57833566cc8a96ffbe47"} build_url:https://queue.taskcluster.net/v1/task/GUpuU_giRxaWAyN0ULUEyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011230 build_url: 'https://queue.taskcluster.net/v1/task/GUpuU_giRxaWAyN0ULUEyQ/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/4e02fd594d77621ac538d4957e085651bf124c6ba756c10c18f0a026683927c87426760cacd647c16314de83b4a0d04e476df1f737421e70f487adedb1b09c1c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12ba2f6e90cbc8bb4801a57abbf18822324e317b853cc444fa37faac8811ea2431d4137f501bad39d7d43ff281ba5e5f6d432fbbdbeb270388c4e6c868b4c733", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ded1b382d67cbee6ccce75c72472960c13875fb88fafaeafc52d8beee183a81552f85211324ea3120a2835392e5368139c298ed762d71b6fe8a67e26dd8d0cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee1ec9ce6be62704a2bdba377daf65fc60c18e12459f0c82910d3b637043206d635563aa3105d5c55e8c36cdb7da8e0d5bb5f2282e8a57833566cc8a96ffbe47"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 15:07:17.633691) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 15:07:17.634056) ========= 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.vGdJP8YZLi/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.1S6GguDfGB/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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.005174 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 15:07:17.683175) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 15:07:17.683517) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 15:07:17.683879) ========= ========= Total master_lag: -0.02 =========