builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0313 starttime: 1494854294.22 results: success (0) revision: 4f45e56b828c9dba63586012672016cb610a3b14 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-15 06:18:14.217087) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-15 06:18:14.217886) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-15 06:18:14.218408) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-15 06:18:14.246345) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 06:18:14.247177) ========= 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.NjUOMBbJLs/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.qsheNtZmGY/Listeners TMPDIR=/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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-15 06:18:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 67.1M=0s 2017-05-15 06:18:14 (67.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.102757 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 06:18:14.375186) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 06:18:14.375540) ========= 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.NjUOMBbJLs/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.qsheNtZmGY/Listeners TMPDIR=/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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.025957 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 06:18:14.431574) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-15 06:18:14.431982) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4f45e56b828c9dba63586012672016cb610a3b14 --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 4f45e56b828c9dba63586012672016cb610a3b14 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NjUOMBbJLs/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.qsheNtZmGY/Listeners TMPDIR=/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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-15 06:18:14,529 truncating revision to first 12 chars 2017-05-15 06:18:14,529 Setting DEBUG logging. 2017-05-15 06:18:14,529 attempt 1/10 2017-05-15 06:18:14,530 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4f45e56b828c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-15 06:18:15,684 unpacking tar archive at: mozilla-central-4f45e56b828c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.449785 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-15 06:18:15.904762) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-15 06:18:15.905150) ========= script_repo_revision: 4f45e56b828c9dba63586012672016cb610a3b14 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-15 06:18:15.905574) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-15 06:18:15.905899) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-15 06:18:15.926491) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-15 06:18:15.926933) ========= /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.NjUOMBbJLs/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.qsheNtZmGY/Listeners TMPDIR=/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 06:18:16 INFO - MultiFileLogger online at 20170515 06:18:16 in /builds/slave/test 06:18:16 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 06:18:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:18:16 INFO - {'append_to_log': False, 06:18:16 INFO - 'base_work_dir': '/builds/slave/test', 06:18:16 INFO - 'blob_upload_branch': 'Firefox', 06:18:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:18:16 INFO - 'branch': 'Firefox', 06:18:16 INFO - 'buildbot_json_path': 'buildprops.json', 06:18:16 INFO - 'config_files': ('talos/mac_config.py',), 06:18:16 INFO - 'default_actions': ('clobber', 06:18:16 INFO - 'read-buildbot-config', 06:18:16 INFO - 'download-and-extract', 06:18:16 INFO - 'populate-webroot', 06:18:16 INFO - 'create-virtualenv', 06:18:16 INFO - 'install', 06:18:16 INFO - 'run-tests'), 06:18:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:18:16 INFO - 'download_minidump_stackwalk': True, 06:18:16 INFO - 'download_symbols': 'ondemand', 06:18:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:18:16 INFO - 'tooltool.py': '/tools/tooltool.py', 06:18:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:18:16 INFO - '/tools/misc-python/virtualenv.py')}, 06:18:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:18:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:18:16 INFO - 'gecko_profile': False, 06:18:16 INFO - 'gecko_profile_interval': 0, 06:18:16 INFO - 'installer_path': 'installer.exe', 06:18:16 INFO - 'log_level': 'info', 06:18:16 INFO - 'log_name': 'talos', 06:18:16 INFO - 'log_to_console': True, 06:18:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:18:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:18:16 INFO - 'opt_config_files': (), 06:18:16 INFO - 'pip_index': False, 06:18:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:18:16 INFO - 'cmd': ('bash', 06:18:16 INFO - '-c', 06:18:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:18:16 INFO - 'enabled': True, 06:18:16 INFO - 'halt_on_failure': False, 06:18:16 INFO - 'name': 'check_screen_resolution'},), 06:18:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:18:16 INFO - 'cmd': ('bash', 06:18:16 INFO - '-c', 06:18:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:18:16 INFO - 'enabled': True, 06:18:16 INFO - 'halt_on_failure': False, 06:18:16 INFO - 'name': 'check_screen_resolution'},), 06:18:16 INFO - 'run_cmd_checks_enabled': True, 06:18:16 INFO - 'suite': 'dromaeojs-e10s', 06:18:16 INFO - 'system_bits': '32', 06:18:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:18:16 INFO - 'title': 't-yosemite-r7-0313', 06:18:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:18:16 INFO - 'use_talos_json': True, 06:18:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:18:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:18:16 INFO - 'work_dir': 'build'} 06:18:16 INFO - [mozharness: 2017-05-15 13:18:16.134754Z] Running clobber step. 06:18:16 INFO - Running pre-action listener: _resource_record_pre_action 06:18:16 INFO - Running main action method: clobber 06:18:16 INFO - rmtree: /builds/slave/test/build 06:18:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:18:18 INFO - Running post-action listener: _resource_record_post_action 06:18:18 INFO - [mozharness: 2017-05-15 13:18:18.551220Z] Finished clobber step (success) 06:18:18 INFO - [mozharness: 2017-05-15 13:18:18.551390Z] Running read-buildbot-config step. 06:18:18 INFO - Running pre-action listener: _resource_record_pre_action 06:18:18 INFO - Running main action method: read_buildbot_config 06:18:18 INFO - Using buildbot properties: 06:18:18 INFO - { 06:18:18 INFO - "project": "", 06:18:18 INFO - "product": "firefox", 06:18:18 INFO - "who": "cbook@mozilla.com", 06:18:18 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 06:18:18 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 06:18:18 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 06:18:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 06:18:18 INFO - "stage_platform": "macosx64", 06:18:18 INFO - "basedir": "/builds/slave/test", 06:18:18 INFO - "buildnumber": 71, 06:18:18 INFO - "platform": "yosemite_r7", 06:18:18 INFO - "parent_task_id": "XrjAjgG6Q-qwE7f4gyJbWw", 06:18:18 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 06:18:18 INFO - "slavebuilddir": "test", 06:18:18 INFO - "taskId": "OisZtHKSSNOcVJ42NHNtvg", 06:18:18 INFO - "branch": "mozilla-central", 06:18:18 INFO - "script_repo_revision": "production", 06:18:18 INFO - "revision": "4f45e56b828c9dba63586012672016cb610a3b14", 06:18:18 INFO - "slavename": "t-yosemite-r7-0313", 06:18:18 INFO - "repo_path": "mozilla-central" 06:18:18 INFO - } 06:18:18 INFO - Finding installer, test and symbols from parent task. 06:18:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OisZtHKSSNOcVJ42NHNtvg'}, attempt #1 06:18:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OisZtHKSSNOcVJ42NHNtvg'}, attempt #1 06:18:19 INFO - Task dependencies: Y5rcrRgtT5uTjrpnNWY5Rw 06:18:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XrjAjgG6Q-qwE7f4gyJbWw'}, attempt #1 06:18:20 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/XrjAjgG6Q-qwE7f4gyJbWw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:18:20 INFO - Set test_url: https://queue.taskcluster.net/v1/task/XrjAjgG6Q-qwE7f4gyJbWw/artifacts/public/build/test_packages.json 06:18:20 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/XrjAjgG6Q-qwE7f4gyJbWw/artifacts/public/build/target.crashreporter-symbols.zip 06:18:20 INFO - Running post-action listener: _resource_record_post_action 06:18:20 INFO - [mozharness: 2017-05-15 13:18:20.337938Z] Finished read-buildbot-config step (success) 06:18:20 INFO - [mozharness: 2017-05-15 13:18:20.338056Z] Running download-and-extract step. 06:18:20 INFO - Running pre-action listener: _resource_record_pre_action 06:18:20 INFO - Running main action method: download_and_extract 06:18:20 INFO - mkdir: /builds/slave/test/build/tests 06:18:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:18:20 INFO - trying https://queue.taskcluster.net/v1/task/XrjAjgG6Q-qwE7f4gyJbWw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 06:18:20 INFO - Downloading https://queue.taskcluster.net/v1/task/XrjAjgG6Q-qwE7f4gyJbWw/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 06:18:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XrjAjgG6Q-qwE7f4gyJbWw/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 06:18:21 INFO - Downloaded 1325 bytes. 06:18:21 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 06:18:21 INFO - Using the following test package requirements: 06:18:21 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:18:21 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 06:18:21 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 06:18:21 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:18:21 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 06:18:21 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:18:21 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 06:18:21 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:18:21 INFO - u'jsshell-mac.zip'], 06:18:21 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:18:21 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 06:18:21 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 06:18:21 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:18:21 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 06:18:21 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:18:21 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 06:18:21 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:18:21 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 06:18:21 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:18:21 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 06:18:21 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:18:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XrjAjgG6Q-qwE7f4gyJbWw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 06:18:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XrjAjgG6Q-qwE7f4gyJbWw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:18:21 INFO - Fetch https://queue.taskcluster.net/v1/task/XrjAjgG6Q-qwE7f4gyJbWw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 06:18:22 INFO - Content-Length response header: 56564685 06:18:22 INFO - Bytes received: 56564685 06:18:40 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 06:18:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XrjAjgG6Q-qwE7f4gyJbWw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 06:18:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XrjAjgG6Q-qwE7f4gyJbWw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:18:40 INFO - Fetch https://queue.taskcluster.net/v1/task/XrjAjgG6Q-qwE7f4gyJbWw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 06:18:42 INFO - Content-Length response header: 56564685 06:18:42 INFO - Bytes received: 56564685 06:18:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XrjAjgG6Q-qwE7f4gyJbWw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 06:18:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XrjAjgG6Q-qwE7f4gyJbWw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:18:59 INFO - Fetch https://queue.taskcluster.net/v1/task/XrjAjgG6Q-qwE7f4gyJbWw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 06:18:59 INFO - Content-Length response header: 14410013 06:18:59 INFO - Bytes received: 14410013 06:19:00 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')]} 06:19:00 INFO - trying https://queue.taskcluster.net/v1/task/XrjAjgG6Q-qwE7f4gyJbWw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:19:00 INFO - Downloading https://queue.taskcluster.net/v1/task/XrjAjgG6Q-qwE7f4gyJbWw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 06:19:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XrjAjgG6Q-qwE7f4gyJbWw/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 06:19:02 INFO - Downloaded 75954430 bytes. 06:19:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XrjAjgG6Q-qwE7f4gyJbWw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:19:02 INFO - mkdir: /builds/slave/test/properties 06:19:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:19:02 INFO - Writing to file /builds/slave/test/properties/build_url 06:19:02 INFO - Contents: 06:19:02 INFO - build_url:https://queue.taskcluster.net/v1/task/XrjAjgG6Q-qwE7f4gyJbWw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:19:02 INFO - Running post-action listener: _resource_record_post_action 06:19:02 INFO - Running post-action listener: set_extra_try_arguments 06:19:02 INFO - [mozharness: 2017-05-15 13:19:02.469489Z] Finished download-and-extract step (success) 06:19:02 INFO - [mozharness: 2017-05-15 13:19:02.469629Z] Running populate-webroot step. 06:19:02 INFO - Running pre-action listener: _resource_record_pre_action 06:19:02 INFO - Running main action method: populate_webroot 06:19:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:19:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:19:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:19:02 INFO - '--tppagecycles', 06:19:02 INFO - '7'], 06:19:02 INFO - 'tests': ['tsvgm']}}, 06:19:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:19:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:19:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:19:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:19:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:19:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:19:02 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:19:02 INFO - 'talos_options': ['--disable-e10s'], 06:19:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:19:02 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:19:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:19:02 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:19:02 INFO - 'talos_options': ['--disable-e10s'], 06:19:02 INFO - 'tests': ['damp', 'tps']}, 06:19:02 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:19:02 INFO - 'tests': ['damp', 'tps']}, 06:19:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:19:02 INFO - 'tests': ['dromaeo_dom']}, 06:19:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:19:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:19:02 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 06:19:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 06:19:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:19:02 INFO - 'tests': ['a11yr', 06:19:02 INFO - 'ts_paint', 06:19:02 INFO - 'tpaint', 06:19:02 INFO - 'sessionrestore', 06:19:02 INFO - 'sessionrestore_no_auto_restore', 06:19:02 INFO - 'tabpaint']}, 06:19:02 INFO - 'other-e10s': {'tests': ['a11yr', 06:19:02 INFO - 'ts_paint', 06:19:02 INFO - 'tpaint', 06:19:02 INFO - 'sessionrestore', 06:19:02 INFO - 'sessionrestore_no_auto_restore', 06:19:02 INFO - 'tabpaint']}, 06:19:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:19:02 INFO - 'ts_paint', 06:19:02 INFO - 'tpaint', 06:19:02 INFO - 'sessionrestore', 06:19:02 INFO - 'sessionrestore_no_auto_restore', 06:19:02 INFO - 'tabpaint']}, 06:19:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:19:02 INFO - 'ts_paint', 06:19:02 INFO - 'tpaint', 06:19:02 INFO - 'sessionrestore', 06:19:02 INFO - 'sessionrestore_no_auto_restore', 06:19:02 INFO - 'tabpaint']}, 06:19:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:19:02 INFO - 'tests': ['a11yr', 06:19:02 INFO - 'ts_paint', 06:19:02 INFO - 'tpaint', 06:19:02 INFO - 'sessionrestore', 06:19:02 INFO - 'sessionrestore_no_auto_restore', 06:19:02 INFO - 'tabpaint']}, 06:19:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:19:02 INFO - 'tests': ['a11yr', 06:19:02 INFO - 'ts_paint', 06:19:02 INFO - 'tpaint', 06:19:02 INFO - 'sessionrestore', 06:19:02 INFO - 'sessionrestore_no_auto_restore', 06:19:02 INFO - 'tabpaint']}, 06:19:02 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 06:19:02 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 06:19:02 INFO - 'bloom_basic_ref']}, 06:19:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:19:02 INFO - 'tests': ['tsvgx', 06:19:02 INFO - 'tsvgr_opacity', 06:19:02 INFO - 'tart', 06:19:02 INFO - 'tscrollx', 06:19:02 INFO - 'cart', 06:19:02 INFO - 'tsvg_static']}, 06:19:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:19:02 INFO - 'tsvgr_opacity', 06:19:02 INFO - 'tart', 06:19:02 INFO - 'tscrollx', 06:19:02 INFO - 'cart', 06:19:02 INFO - 'tsvg_static']}, 06:19:02 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:19:02 INFO - 'talos_options': ['--disable-e10s'], 06:19:02 INFO - 'tests': ['tp5o']}, 06:19:02 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:19:02 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:19:02 INFO - 'talos_options': ['--disable-e10s', 06:19:02 INFO - '--xperf_path', 06:19:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:19:02 INFO - 'tests': ['tp5n']}, 06:19:02 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:19:02 INFO - 'talos_options': ['--xperf_path', 06:19:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:19:02 INFO - 'tests': ['tp5n']}}, 06:19:02 INFO - 'talos.zip': {'path': '', 06:19:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:19:02 INFO - Running post-action listener: _resource_record_post_action 06:19:02 INFO - [mozharness: 2017-05-15 13:19:02.490920Z] Finished populate-webroot step (success) 06:19:02 INFO - [mozharness: 2017-05-15 13:19:02.491060Z] Running create-virtualenv step. 06:19:02 INFO - Running pre-action listener: _resource_record_pre_action 06:19:02 INFO - Running main action method: create_virtualenv 06:19:02 INFO - Creating virtualenv /builds/slave/test/build/venv 06:19:02 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 06:19:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:19:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:19:02 INFO - Using real prefix '/tools/python27' 06:19:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:19:03 INFO - Installing distribute.............................................................................................................................................................................................done. 06:19:06 INFO - Installing pip.................done. 06:19:06 INFO - Return code: 0 06:19:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:19:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:19:06 INFO - Reading from file tmpfile_stdout 06:19:06 INFO - Output received: 06:19:06 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) 06:19:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:19:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:19:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f4d8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4dc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb7b5640a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1e8d30>, '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 0x10f19cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1ddbe8>, '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.NjUOMBbJLs/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.qsheNtZmGY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 06:19:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 06:19:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:19:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NjUOMBbJLs/Render', 06:19:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:06 INFO - 'HOME': '/Users/cltbld', 06:19:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:06 INFO - 'LOGNAME': 'cltbld', 06:19:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:06 INFO - 'MOZ_NO_REMOTE': '1', 06:19:06 INFO - 'NO_EM_RESTART': '1', 06:19:06 INFO - 'PAGER': '/bin/cat', 06:19:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:06 INFO - 'PWD': '/builds/slave/test', 06:19:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:06 INFO - 'SHELL': '/bin/bash', 06:19:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qsheNtZmGY/Listeners', 06:19:06 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 06:19:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:06 INFO - 'USER': 'cltbld', 06:19:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:06 INFO - 'XPC_FLAGS': '0x0', 06:19:06 INFO - 'XPC_SERVICE_NAME': '0', 06:19:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:06 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 06:19:06 INFO - Cleaning up... 06:19:06 INFO - Return code: 0 06:19:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:19:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:19:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f4d8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4dc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb7b5640a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1e8d30>, '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 0x10f19cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1ddbe8>, '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.NjUOMBbJLs/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.qsheNtZmGY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 06:19:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 06:19:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:19:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NjUOMBbJLs/Render', 06:19:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:06 INFO - 'HOME': '/Users/cltbld', 06:19:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:06 INFO - 'LOGNAME': 'cltbld', 06:19:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:06 INFO - 'MOZ_NO_REMOTE': '1', 06:19:06 INFO - 'NO_EM_RESTART': '1', 06:19:06 INFO - 'PAGER': '/bin/cat', 06:19:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:06 INFO - 'PWD': '/builds/slave/test', 06:19:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:06 INFO - 'SHELL': '/bin/bash', 06:19:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qsheNtZmGY/Listeners', 06:19:06 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 06:19:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:06 INFO - 'USER': 'cltbld', 06:19:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:06 INFO - 'XPC_FLAGS': '0x0', 06:19:06 INFO - 'XPC_SERVICE_NAME': '0', 06:19:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:06 INFO - Downloading/unpacking psutil>=3.1.1 06:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:19:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:19:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:19:08 INFO - Installing collected packages: psutil 06:19:08 INFO - Running setup.py install for psutil 06:19:09 INFO - building 'psutil._psutil_osx' extension 06:19:09 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 06:19:09 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 06:19:09 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 06:19:09 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 06:19:09 INFO - building 'psutil._psutil_posix' extension 06:19:09 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 06:19:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:19:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:19:09 INFO - ^ 06:19:09 INFO - 1 warning generated. 06:19:09 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 06:19:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:19:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:19:10 INFO - Successfully installed psutil 06:19:10 INFO - Cleaning up... 06:19:10 INFO - Return code: 0 06:19:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:19:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:19:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f4d8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4dc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb7b5640a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1e8d30>, '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 0x10f19cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1ddbe8>, '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.NjUOMBbJLs/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.qsheNtZmGY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 06:19:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:19:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:19:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NjUOMBbJLs/Render', 06:19:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:10 INFO - 'HOME': '/Users/cltbld', 06:19:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:10 INFO - 'LOGNAME': 'cltbld', 06:19:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:10 INFO - 'MOZ_NO_REMOTE': '1', 06:19:10 INFO - 'NO_EM_RESTART': '1', 06:19:10 INFO - 'PAGER': '/bin/cat', 06:19:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:10 INFO - 'PWD': '/builds/slave/test', 06:19:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:10 INFO - 'SHELL': '/bin/bash', 06:19:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qsheNtZmGY/Listeners', 06:19:10 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 06:19:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:10 INFO - 'USER': 'cltbld', 06:19:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:10 INFO - 'XPC_FLAGS': '0x0', 06:19:10 INFO - 'XPC_SERVICE_NAME': '0', 06:19:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:19: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 06:19: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 06:19: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 06:19: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 06:19:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:19:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:19:12 INFO - Installing collected packages: mozsystemmonitor 06:19:12 INFO - Running setup.py install for mozsystemmonitor 06:19:13 INFO - Successfully installed mozsystemmonitor 06:19:13 INFO - Cleaning up... 06:19:13 INFO - Return code: 0 06:19:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:19: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')]} 06:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19: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')]} 06:19:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f4d8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4dc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb7b5640a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1e8d30>, '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 0x10f19cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1ddbe8>, '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.NjUOMBbJLs/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.qsheNtZmGY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 06:19: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==2.5.1'] in /builds/slave/test/build 06:19: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==2.5.1 06:19:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NjUOMBbJLs/Render', 06:19:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:13 INFO - 'HOME': '/Users/cltbld', 06:19:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:13 INFO - 'LOGNAME': 'cltbld', 06:19:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:13 INFO - 'MOZ_NO_REMOTE': '1', 06:19:13 INFO - 'NO_EM_RESTART': '1', 06:19:13 INFO - 'PAGER': '/bin/cat', 06:19:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:13 INFO - 'PWD': '/builds/slave/test', 06:19:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:13 INFO - 'SHELL': '/bin/bash', 06:19:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qsheNtZmGY/Listeners', 06:19:13 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 06:19:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:13 INFO - 'USER': 'cltbld', 06:19:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:13 INFO - 'XPC_FLAGS': '0x0', 06:19:13 INFO - 'XPC_SERVICE_NAME': '0', 06:19:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:13 INFO - Downloading/unpacking jsonschema==2.5.1 06:19:13 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 06:19:13 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 06:19:13 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 06:19:13 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 06:19:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:19:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:19:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:14 INFO - Downloading functools32-3.2.3-2.tar.gz 06:19:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:19:14 INFO - warning: no files found matching '*.txt' 06:19:14 INFO - no previously-included directories found matching 'build' 06:19:14 INFO - no previously-included directories found matching 'dist' 06:19:14 INFO - no previously-included directories found matching '.git*' 06:19:15 INFO - Installing collected packages: jsonschema, functools32 06:19:15 INFO - Running setup.py install for functools32 06:19:15 INFO - warning: no files found matching '*.txt' 06:19:15 INFO - no previously-included directories found matching 'build' 06:19:15 INFO - no previously-included directories found matching 'dist' 06:19:15 INFO - no previously-included directories found matching '.git*' 06:19:15 INFO - Successfully installed jsonschema functools32 06:19:15 INFO - Cleaning up... 06:19:15 INFO - Return code: 0 06:19:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:19:15 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')]} 06:19:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:15 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')]} 06:19:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:15 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 0x10f4d8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4dc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb7b5640a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1e8d30>, '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 0x10f19cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1ddbe8>, '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.NjUOMBbJLs/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.qsheNtZmGY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 06:19:15 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 06:19:15 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 06:19:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NjUOMBbJLs/Render', 06:19:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:15 INFO - 'HOME': '/Users/cltbld', 06:19:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:15 INFO - 'LOGNAME': 'cltbld', 06:19:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:15 INFO - 'MOZ_NO_REMOTE': '1', 06:19:15 INFO - 'NO_EM_RESTART': '1', 06:19:15 INFO - 'PAGER': '/bin/cat', 06:19:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:15 INFO - 'PWD': '/builds/slave/test', 06:19:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:15 INFO - 'SHELL': '/bin/bash', 06:19:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qsheNtZmGY/Listeners', 06:19:15 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 06:19:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:15 INFO - 'USER': 'cltbld', 06:19:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:15 INFO - 'XPC_FLAGS': '0x0', 06:19:15 INFO - 'XPC_SERVICE_NAME': '0', 06:19:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:19:15 INFO - Cleaning up... 06:19:15 INFO - Return code: 0 06:19:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:19:15 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')]} 06:19:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:15 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')]} 06:19:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:15 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 0x10f4d8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4dc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb7b5640a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1e8d30>, '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 0x10f19cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1ddbe8>, '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.NjUOMBbJLs/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.qsheNtZmGY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 06:19:15 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 06:19:15 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 06:19:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NjUOMBbJLs/Render', 06:19:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:15 INFO - 'HOME': '/Users/cltbld', 06:19:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:15 INFO - 'LOGNAME': 'cltbld', 06:19:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:15 INFO - 'MOZ_NO_REMOTE': '1', 06:19:15 INFO - 'NO_EM_RESTART': '1', 06:19:15 INFO - 'PAGER': '/bin/cat', 06:19:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:15 INFO - 'PWD': '/builds/slave/test', 06:19:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:15 INFO - 'SHELL': '/bin/bash', 06:19:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qsheNtZmGY/Listeners', 06:19:15 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 06:19:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:15 INFO - 'USER': 'cltbld', 06:19:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:15 INFO - 'XPC_FLAGS': '0x0', 06:19:15 INFO - 'XPC_SERVICE_NAME': '0', 06:19:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:15 INFO - Downloading/unpacking blobuploader==1.2.4 06:19:15 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 06:19:15 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 06:19:15 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 06:19:15 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 06:19:17 INFO - Downloading blobuploader-1.2.4.tar.gz 06:19:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:19:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:19:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:17 INFO - Downloading docopt-0.6.1.tar.gz 06:19:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:19:18 INFO - Installing collected packages: blobuploader, requests, docopt 06:19:18 INFO - Running setup.py install for blobuploader 06:19:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:19:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:19:18 INFO - Running setup.py install for requests 06:19:18 INFO - Running setup.py install for docopt 06:19:18 INFO - Successfully installed blobuploader requests docopt 06:19:18 INFO - Cleaning up... 06:19:18 INFO - Return code: 0 06:19:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:19:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:19:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:19:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:18 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 0x10f4d8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4dc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb7b5640a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1e8d30>, '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 0x10f19cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1ddbe8>, '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.NjUOMBbJLs/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.qsheNtZmGY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 06:19:18 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 06:19:18 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 06:19:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NjUOMBbJLs/Render', 06:19:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:18 INFO - 'HOME': '/Users/cltbld', 06:19:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:18 INFO - 'LOGNAME': 'cltbld', 06:19:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:18 INFO - 'MOZ_NO_REMOTE': '1', 06:19:18 INFO - 'NO_EM_RESTART': '1', 06:19:18 INFO - 'PAGER': '/bin/cat', 06:19:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:18 INFO - 'PWD': '/builds/slave/test', 06:19:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:18 INFO - 'SHELL': '/bin/bash', 06:19:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qsheNtZmGY/Listeners', 06:19:18 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 06:19:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:18 INFO - 'USER': 'cltbld', 06:19:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:18 INFO - 'XPC_FLAGS': '0x0', 06:19:18 INFO - 'XPC_SERVICE_NAME': '0', 06:19:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:19:19 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-DoA6Ar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:19:19 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-gydkZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:19:19 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-DlIM66-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:19:19 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-mRh9sZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:19:19 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-4nt5IL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:19:19 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-v1bIe5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:19:19 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-e8Jzng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:19:19 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-VGuLp5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:19:20 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-WKf5or-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:19:20 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-5pR09t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:19:20 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-7BwPxG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:19:20 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-_B0I7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:19:20 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-xCiKzW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:19:20 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-xMTsMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:19:20 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-5BlAEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:19:20 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-jb9t5c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:19:20 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-b8fkUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:19:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:19:20 INFO - Running setup.py install for manifestparser 06:19:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:19:21 INFO - Running setup.py install for mozcrash 06:19:21 INFO - Running setup.py install for mozdebug 06:19:21 INFO - Running setup.py install for mozdevice 06:19:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:19:21 INFO - Running setup.py install for mozfile 06:19:21 INFO - Running setup.py install for mozhttpd 06:19:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:19:21 INFO - Running setup.py install for mozinfo 06:19:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:19:21 INFO - Running setup.py install for mozInstall 06:19:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:19:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:19:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:19:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:19:22 INFO - Running setup.py install for mozleak 06:19:22 INFO - Running setup.py install for mozlog 06:19:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:19:22 INFO - Running setup.py install for moznetwork 06:19:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:19:22 INFO - Running setup.py install for mozprocess 06:19:22 INFO - Running setup.py install for mozprofile 06:19:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:19:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:19:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:19:22 INFO - Running setup.py install for mozrunner 06:19:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:19:23 INFO - Running setup.py install for mozscreenshot 06:19:23 INFO - Running setup.py install for moztest 06:19:23 INFO - Running setup.py install for mozversion 06:19:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:19:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:19:23 INFO - Cleaning up... 06:19:23 INFO - Return code: 0 06:19:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:19:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:23 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 0x10f4d8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4dc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb7b5640a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1e8d30>, '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 0x10f19cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1ddbe8>, '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.NjUOMBbJLs/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.qsheNtZmGY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 06:19:23 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 06:19:23 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 06:19:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NjUOMBbJLs/Render', 06:19:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:23 INFO - 'HOME': '/Users/cltbld', 06:19:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:23 INFO - 'LOGNAME': 'cltbld', 06:19:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:23 INFO - 'MOZ_NO_REMOTE': '1', 06:19:23 INFO - 'NO_EM_RESTART': '1', 06:19:23 INFO - 'PAGER': '/bin/cat', 06:19:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:23 INFO - 'PWD': '/builds/slave/test', 06:19:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:23 INFO - 'SHELL': '/bin/bash', 06:19:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qsheNtZmGY/Listeners', 06:19:23 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 06:19:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:23 INFO - 'USER': 'cltbld', 06:19:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:23 INFO - 'XPC_FLAGS': '0x0', 06:19:23 INFO - 'XPC_SERVICE_NAME': '0', 06:19:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:19:23 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-QWXtDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:19:23 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)) 06:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:19:23 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-EpZKmD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:19:24 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)) 06:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:19:24 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-0nUUgc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:19:24 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)) 06:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:19:24 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-qKRtLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:19:24 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-HDzzy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:19:24 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)) 06:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:19:24 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-BJzR_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:19:24 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)) 06:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:19:24 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-M0sIg0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:19:24 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)) 06:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:19:24 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-0yQH_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:19:24 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)) 06:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:19:24 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-X2LaOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:19:24 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)) 06:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:19:24 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-iyGf_x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:19:24 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)) 06:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:19:24 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-PCbcKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:19:24 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)) 06:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:19:24 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-ep9pPs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:19:25 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)) 06:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:19:25 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-Y_P_tA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:19:25 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)) 06:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:19:25 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-W_fLWr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:19:25 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)) 06:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:19:25 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-8UXJfN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:19:25 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)) 06:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:19:25 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-U5uLEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:19:25 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)) 06:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:19:25 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-Uyd6Dp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:19:25 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)) 06:19:25 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)) 06:19:25 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)) 06:19:25 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)) 06:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:19:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:19:25 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 06:19:25 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 06:19:25 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 06:19:25 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 06:19:26 INFO - Downloading blessings-1.6.tar.gz 06:19:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:19:27 INFO - Installing collected packages: blessings 06:19:27 INFO - Running setup.py install for blessings 06:19:27 INFO - Successfully installed blessings 06:19:27 INFO - Cleaning up... 06:19:27 INFO - Return code: 0 06:19:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:19:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:19:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:19:27 INFO - Reading from file tmpfile_stdout 06:19:27 INFO - Current package versions: 06:19:27 INFO - blessings == 1.6 06:19:27 INFO - blobuploader == 1.2.4 06:19:27 INFO - docopt == 0.6.1 06:19:27 INFO - functools32 == 3.2.3-2 06:19:27 INFO - jsonschema == 2.5.1 06:19:27 INFO - manifestparser == 1.1 06:19:27 INFO - mozInstall == 1.12 06:19:27 INFO - mozcrash == 1.0 06:19:27 INFO - mozdebug == 0.1 06:19:27 INFO - mozdevice == 0.50 06:19:27 INFO - mozfile == 1.2 06:19:27 INFO - mozhttpd == 0.7 06:19:27 INFO - mozinfo == 0.9 06:19:27 INFO - mozleak == 0.1 06:19:27 INFO - mozlog == 3.4 06:19:27 INFO - moznetwork == 0.27 06:19:27 INFO - mozprocess == 0.25 06:19:27 INFO - mozprofile == 0.28 06:19:27 INFO - mozrunner == 6.13 06:19:27 INFO - mozscreenshot == 0.1 06:19:27 INFO - mozsystemmonitor == 0.3 06:19:27 INFO - moztest == 0.8 06:19:27 INFO - mozversion == 1.4 06:19:27 INFO - psutil == 3.1.1 06:19:27 INFO - requests == 1.2.3 06:19:27 INFO - wsgiref == 0.1.2 06:19:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:19:27 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')]} 06:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:27 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')]} 06:19:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:27 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 0x10f4d8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4dc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb7b5640a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1e8d30>, '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 0x10f19cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1ddbe8>, '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.NjUOMBbJLs/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.qsheNtZmGY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 06:19:27 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 06:19:27 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 06:19:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NjUOMBbJLs/Render', 06:19:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:27 INFO - 'HOME': '/Users/cltbld', 06:19:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:27 INFO - 'LOGNAME': 'cltbld', 06:19:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:27 INFO - 'MOZ_NO_REMOTE': '1', 06:19:27 INFO - 'NO_EM_RESTART': '1', 06:19:27 INFO - 'PAGER': '/bin/cat', 06:19:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:27 INFO - 'PWD': '/builds/slave/test', 06:19:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:27 INFO - 'SHELL': '/bin/bash', 06:19:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qsheNtZmGY/Listeners', 06:19:27 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 06:19:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:27 INFO - 'USER': 'cltbld', 06:19:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:27 INFO - 'XPC_FLAGS': '0x0', 06:19:27 INFO - 'XPC_SERVICE_NAME': '0', 06:19:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:27 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)) 06:19:27 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)) 06:19:27 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)) 06:19:27 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)) 06:19:27 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)) 06:19:27 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)) 06:19:27 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)) 06:19:27 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)) 06:19:27 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)) 06:19:27 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)) 06:19:27 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)) 06:19:27 INFO - Cleaning up... 06:19:27 INFO - Return code: 0 06:19:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:19:27 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')]} 06:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:27 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')]} 06:19:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:27 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 0x10f4d8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4dc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb7b5640a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1e8d30>, '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 0x10f19cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1ddbe8>, '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.NjUOMBbJLs/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.qsheNtZmGY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 06:19:27 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 06:19:27 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 06:19:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NjUOMBbJLs/Render', 06:19:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:27 INFO - 'HOME': '/Users/cltbld', 06:19:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:27 INFO - 'LOGNAME': 'cltbld', 06:19:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:27 INFO - 'MOZ_NO_REMOTE': '1', 06:19:27 INFO - 'NO_EM_RESTART': '1', 06:19:27 INFO - 'PAGER': '/bin/cat', 06:19:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:27 INFO - 'PWD': '/builds/slave/test', 06:19:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:27 INFO - 'SHELL': '/bin/bash', 06:19:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qsheNtZmGY/Listeners', 06:19:27 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 06:19:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:27 INFO - 'USER': 'cltbld', 06:19:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:27 INFO - 'XPC_FLAGS': '0x0', 06:19:27 INFO - 'XPC_SERVICE_NAME': '0', 06:19:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:19:28 INFO - Cleaning up... 06:19:28 INFO - Return code: 0 06:19:28 INFO - Running post-action listener: _resource_record_post_action 06:19:28 INFO - Running post-action listener: _start_resource_monitoring 06:19:28 INFO - Starting resource monitoring. 06:19:28 INFO - [mozharness: 2017-05-15 13:19:28.238083Z] Finished create-virtualenv step (success) 06:19:28 INFO - [mozharness: 2017-05-15 13:19:28.239058Z] Running install step. 06:19:28 INFO - Running pre-action listener: _resource_record_pre_action 06:19:28 INFO - Running main action method: install 06:19:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:19:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:19:28 INFO - Reading from file tmpfile_stdout 06:19:28 INFO - Detecting whether we're running mozinstall >=1.0... 06:19:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:19:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:19:28 INFO - Reading from file tmpfile_stdout 06:19:28 INFO - Output received: 06:19:28 INFO - Usage: mozinstall [options] installer 06:19:28 INFO - Options: 06:19:28 INFO - -h, --help show this help message and exit 06:19:28 INFO - -d DEST, --destination=DEST 06:19:28 INFO - Directory to install application into. [default: 06:19:28 INFO - "/builds/slave/test"] 06:19:28 INFO - --app=APP Application being installed. [default: firefox] 06:19:28 INFO - mkdir: /builds/slave/test/build/application 06:19:28 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'] 06:19:28 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 06:19:49 INFO - Reading from file tmpfile_stdout 06:19:49 INFO - Output received: 06:19:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:19:49 INFO - Running post-action listener: _resource_record_post_action 06:19:49 INFO - [mozharness: 2017-05-15 13:19:49.072076Z] Finished install step (success) 06:19:49 INFO - [mozharness: 2017-05-15 13:19:49.072221Z] Running run-tests step. 06:19:49 INFO - Running pre-action listener: _resource_record_pre_action 06:19:49 INFO - Running main action method: run_tests 06:19:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:19:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:19:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:19:49 INFO - 2017-05-15 06:19:49.086 screenresolution[1631:7535] starting screenresolution argv=screenresolution get 06:19:49 INFO - 2017-05-15 06:19:49.089 screenresolution[1631:7535] Display 0: 1600x1200x32@60 06:19:49 INFO - 2017-05-15 06:19:49.095 screenresolution[1632:7537] starting screenresolution argv=screenresolution list 06:19:49 INFO - Available Modes on Display 0 06:19:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:19:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:19:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:19:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:19:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:19:49 INFO - Intel Iris: 06:19:49 INFO - Chipset Model: Intel Iris 06:19:49 INFO - Type: GPU 06:19:49 INFO - Bus: Built-In 06:19:49 INFO - VRAM (Dynamic, Max): 1536 MB 06:19:49 INFO - Vendor: Intel (0x8086) 06:19:49 INFO - Device ID: 0x0a2e 06:19:49 INFO - Revision ID: 0x0009 06:19:49 INFO - Displays: 06:19:49 INFO - Display: 06:19:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:19:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:19:49 INFO - Display Serial Number: 1600x1200 60 06:19:49 INFO - Main Display: Yes 06:19:49 INFO - Mirror: Off 06:19:49 INFO - Online: Yes 06:19:49 INFO - Rotation: Supported 06:19:49 INFO - Return code: 0 06:19:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:19:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:19:49 INFO - Python 2.7.3 06:19:49 INFO - Return code: 0 06:19:49 INFO - grabbing minidump binary from tooltool 06:19:49 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')]} 06:19:49 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 0x10f1e8d30>, '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 0x10f19cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1ddbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:19:49 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 06:19:49 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 06:19:49 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 06:19:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:19:49 INFO - Return code: 0 06:19:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:19:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:19:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:19:49 INFO - ENV: RUST_BACKTRACE is now 1 06:19:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:19:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:19:49 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/XrjAjgG6Q-qwE7f4gyJbWw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0313', '--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 06:19:49 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/XrjAjgG6Q-qwE7f4gyJbWw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0313 --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 06:19:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NjUOMBbJLs/Render', 06:19:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:49 INFO - 'HOME': '/Users/cltbld', 06:19:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:49 INFO - 'LOGNAME': 'cltbld', 06:19:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:19:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:19:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:49 INFO - 'MOZ_NO_REMOTE': '1', 06:19:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:19:49 INFO - 'NO_EM_RESTART': '1', 06:19:49 INFO - 'PAGER': '/bin/cat', 06:19:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:49 INFO - 'PWD': '/builds/slave/test', 06:19:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:19:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:49 INFO - 'RUST_BACKTRACE': '1', 06:19:49 INFO - 'SHELL': '/bin/bash', 06:19:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qsheNtZmGY/Listeners', 06:19:49 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 06:19:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:49 INFO - 'USER': 'cltbld', 06:19:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:49 INFO - 'XPC_FLAGS': '0x0', 06:19:49 INFO - 'XPC_SERVICE_NAME': '0', 06:19:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:49 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/XrjAjgG6Q-qwE7f4gyJbWw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0313', '--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 06:19:49 INFO - mozversion application_buildid: 20170515045544 06:19:49 INFO - mozversion application_changeset: 4f45e56b828c9dba63586012672016cb610a3b14 06:19:49 INFO - mozversion application_display_name: Nightly 06:19:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:19:49 INFO - mozversion application_name: Firefox 06:19:49 INFO - mozversion application_remotingname: firefox 06:19:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:19:49 INFO - mozversion application_vendor: Mozilla 06:19:49 INFO - mozversion application_version: 55.0a1 06:19:49 INFO - mozversion platform_buildid: 20170515045544 06:19:49 INFO - mozversion platform_changeset: 4f45e56b828c9dba63586012672016cb610a3b14 06:19:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:19:49 INFO - mozversion platform_version: 55.0a1 06:19:49 INFO - using testdate: 1494854389 06:19:49 INFO - actual date: 1494854389 06:19:49 INFO - starting webserver on 'localhost:49227' 06:19:49 INFO - SUITE-START | Running 2 tests 06:19:49 INFO - TEST-START | dromaeo_css 06:19:49 INFO - operating with platform_type : mac_ 06:19:49 INFO - Initialising browser for dromaeo_css test... 06:19:49 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/tmp8NdsOL/profile http://localhost:49227/getInfo.html) 06:19:50 INFO - PID 1642 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2932: String.localeCompare is deprecated; use String.prototype.localeCompare instead 06:19:52 INFO - PID 1642 | 2017-05-15 06:19:52.427 plugin-container[1644:7725] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 06:19:52 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:19:52 INFO - PID 1642 | 2017-05-15 06:19:52.429 plugin-container[1644:7725] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c17, name = 'com.apple.CFPasteboardClient' 06:19:52 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:19:52 INFO - PID 1642 | 2017-05-15 06:19:52.429 plugin-container[1644:7725] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:19:52 INFO - PID 1642 | __metrics Screen width/height:1600/1200 06:19:52 INFO - PID 1642 | colorDepth:24 06:19:52 INFO - PID 1642 | Browser inner width/height: 1024/691 06:19:52 INFO - PID 1642 | __metrics 06:19:52 INFO - PID 1642 | 2017-05-15 06:19:52.910 plugin-container[1645:7775] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903f, name = 'com.apple.tsm.portname' 06:19:52 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:19:52 INFO - PID 1642 | 2017-05-15 06:19:52.911 plugin-container[1645:7775] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 06:19:52 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:19:52 INFO - PID 1642 | 2017-05-15 06:19:52.911 plugin-container[1645:7775] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:19:53 INFO - TEST-INFO | 1642: exit 0 06:19:53 INFO - Browser initialized. 06:19:53 INFO - Running cycle 1/1 for dromaeo_css test... 06:19:53 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/tmp8NdsOL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:19:55 INFO - PID 1646 | 2017-05-15 06:19:55.476 plugin-container[1647:7877] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4743, name = 'com.apple.tsm.portname' 06:19:55 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:19:55 INFO - PID 1646 | 2017-05-15 06:19:55.477 plugin-container[1647:7877] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9717, name = 'com.apple.CFPasteboardClient' 06:19:55 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:19:55 INFO - PID 1646 | 2017-05-15 06:19:55.477 plugin-container[1647:7877] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:19:56 INFO - PID 1646 | 2017-05-15 06:19:56.081 plugin-container[1648:7923] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 06:19:56 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:19:56 INFO - PID 1646 | 2017-05-15 06:19:56.081 plugin-container[1648:7923] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 06:19:56 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:19:56 INFO - PID 1646 | 2017-05-15 06:19:56.081 plugin-container[1648:7923] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:20:05 INFO - PID 1646 | RSS: Main: 234074112 06:20:05 INFO - PID 1646 | 06:23:09 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49227/tests/dromaeo/cssquery-ext.html) 06:23:10 INFO - PID 1646 | RSS: Main: 256073728 06:23:10 INFO - PID 1646 | 06:26:12 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-ext.html (next: http://localhost:49227/tests/dromaeo/cssquery-jquery.html) 06:26:13 INFO - PID 1646 | RSS: Main: 264785920 06:26:13 INFO - PID 1646 | 06:29:14 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49227/tests/dromaeo/cssquery-mootools.html) 06:29:15 INFO - PID 1646 | RSS: Main: 268734464 06:29:15 INFO - PID 1646 | 06:32:17 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49227/tests/dromaeo/cssquery-prototype.html) 06:32:17 INFO - PID 1646 | RSS: Main: 266555392 06:32:17 INFO - PID 1646 | 06:35:20 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49227/tests/dromaeo/cssquery-yui.html) 06:35:21 INFO - PID 1646 | RSS: Main: 268165120 06:35:21 INFO - PID 1646 | 06:38:22 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-yui.html (next: http://localhost:49227/tests/dromaeo/cssquery-dojo.html) 06:38:23 INFO - PID 1646 | RSS: Main: 269639680 06:38:23 INFO - PID 1646 | 06:38:23 INFO - PID 1646 | __start_tp_report 06:38:23 INFO - PID 1646 | _x_x_mozilla_page_load 06:38:23 INFO - PID 1646 | _x_x_mozilla_page_load_details 06:38:23 INFO - PID 1646 | |i|pagename|runs| 06:38:23 INFO - PID 1646 | |0;dojo.html;2779.874150968857;3410.931781364373;3579.355715971126;3633.091727068233;3639.344917914774;6074.726637301321;6181.072839074135;6202.751889924403;6217.968910155449;6262.812115636527;6142.652254521286;6160.876782464359;6201.193844800181;6206.193194884659;6262.718177682003;5407.080796264644;5445.37378201506;5447.346318441772;5451.890962180754;5458.263134476854;5494.560435165177;5548.4174161713045;5553.305836770403;5566.471185237396;5583.637063590874;5347.064263753867;5402.837914862561;5440.075187218183;5487.6981766002855;5549.084401073818;5461.890762184774;5475.561955043587;5515.889682206373;5529.170624406331;5543.6119471637;5244.81643142492;5441.918371224435;5466.671999680032;5467.425920278353;5502.5597952163735;4050.797460127011;5400.83797486076;5567.582431317667;5792.507636850831;6142.539309551757;6381.553291269613;6450.4839612830865;6548.70530826114;6681.556670090014;6747.460203183734;5210.687358758449;5534.667919924817;6784.151981002375;6789.15135608049;6813.659317034128;6152.8769424611255;6270.561060725751;6304.905426418607;6322.146510221107;6329.936700632981;6694.263631000586;6820.727170913207;6926.688299026556;6927.826804329932;6958.199807022156;6508;6561.671916404161;6595.472362211011;6603.70283337251;6634.900476492857;5095.643304968691;6766.763163289262;6767.154105736783;6793.66031698423;6817.318268173242;5599.880023995217;6460.45086167681;6482;6505.609663420115;6602.778485980013;6703.3296670332575;6719.294474080249;6725.05849181115;6730.562513436611;6754.257031726506;5194.27280180775;5205.7397130143345;5234.895302093937;5244.816431424882;5306.177542480922;4719.197736384823;4760.547747963938;4781.95218047822;4853.587445067209;4864.027194561102;5171.844844654666;6371.108044873721;6417.96791016042;6437.420632143129;6448.065030570541;5620.269364982608;6822.283660215705;6823.863522729517;6849.1780986281965;6853.246142924274;6255.311915689271;6315.905261421082;6334.904976425358;6443.581167224115;6467.223933127961;5801.53587712982;5885.2349194605285;5943.821685349447;5954.434328738763;5978.5814992950945;6927.307269273234;6937.098177236927;6995.055667484824;6996.545224560591;7017.649117544001;5886.764529418947;6806.591604503746;6813.318668133147;6831.726730930707;6853.143357080365;5591.30108736408;6693.866122677617;6820.181578210647;6854.0404343390965;7024.402925751164;6600.471962242913;6671.966640166768;6702.865942681216;6708.161479815023;6715.630640314829;6755.7973260802255;6768.357006084414;6787.117674702455;6814.761483348059;6815.079964204769;6356.841078973062;6496.837579060561;6543.738250469928;6589.571677840925;6613.338666133348;6518.282988871126;6549.508786841101;6574.980877963923;6587.011948207807;6591.868162636816;5621.775128994795;6040.607360521552;6241.438270555672;6300.086487459201;6421.8715625688155;6430.196225471816;6449.613023218434;6520.152380190545;6610.8016759497295;6625.734970601122;5853.151293062397;6369.1401660777055;6434.742610295723;6448.709808058742;6461.418472337512 06:38:23 INFO - PID 1646 | |1;ext.html;798.0901771979944;811;831.56342919967;1034.3742036068174;1191.4817054581258;38388.23223535531;39461.40807887884;39482.012949676275;39544.802275988615;39626.00934976627;39170.60829391705;39418.81743547691;39491;39628;39654.206915861665;7936.127026027132;8347.29048030918;8537.694822397949;8538.231559159674;8547;7422.888656670154;8528.914710852905;8542.188492093272;8617.913820861806;8627.352948528855;7408.185099639036;8485.151484851527;8575.742727718178;8588;8590.055093939662;8522.488650680947;8544.61549230283;8546.572671366406;8572.742817715478;8576.914230857705;8394.034686011126;8458.238758511763;8465.703700370519;8471.872921906177;8474.703385381483;37097.62902370969;37136.62863371386;37188;37190.69832555875;37493;3693.3905905525558;3693.501377314055;3694.907627309312;3725.217704282104;3734.159814041846;30392.240193995105;30527.236819079477;30648.693513064805;30654.846725766452;30717.846410768027;3141.528770684393;3188.4420226460275;3191.712745852861;3196.6483686794436;3203.5194720791833;252140;257180;282552;293713;296127;48499.757501212975;48673.26990095151;48793.268100978516;48798.02403951972;48882;51046.7238319045;51051.48948510467;51070.48929510657;51100.23349649758;51145.7442712784;13943.790843137362;14021.719565608631;14078.71842563143;14097.577072687836;14107.929460352632;11563.537458501734;11576.131790115774;11587.130965177645;11596.826047609293;11622.6513204604;13574.389152488162;13629.522966696317;13772.724545509034;13854.65363365924;13923.65190870216;6159.137720719103;6248.875022499525;6257.342978987232;6261.373862613793;6278.937210627835;1021.545412291538;1035.7358873487212;1043.7181960870523;1075.295681328731;1093.4478088565384;24104.75895241025;25117.497650047262;25260.747392526206;25275.24174274775;25297.114600988876;931.9347645664874;1103.4372470040337;1179.2747460311846;1179.5281887244998;1190.4642910690225;10900.40047797363;12363.072769542321;12771.808422873664;12902.35488225585;12905.870941290656;314154;316837;316846;317301;317613;54151.729241354325;54615.18077228845;54713.726431368385;54766.45233547613;54847;1559.5711179425523;1585.58774718574;1608.702390057843;1609.1954022988505;1634.5014770494874;24449.144279950116;24582.016719331026;24644.50710985806;24711.135110271774;24735.010599576537;38748.6125138745;49138.75430622824;49236.75381623212;49251.75374123106;49336;252852;254004;254152;254537;254679;33514.329713406085;33903.491447628796;34973;35207.11982200466;35381.29237415186;42612.360814587184;43559.56440435555;45671.54328456673;45751.77124114491;46257.30614040725;27790.583141252493;29313.41373172482;30779.692203077684;31420.685793141776;31442.685573143975;28228.85885570641;30520.084397468112;31029;31699.20751981219;31866.681333187298;36575.08562285964;36998.6300137006;37552.41155627982;37824.05439863916;37880.81059594794;26254.868725656248;27289.590656139775;30129.24676883008;30317.84841075869;30477;17417.738733918748;24435;24571.877140614182;24924.12765553197;24938.12716554912 06:38:23 INFO - PID 1646 | |2;jquery.html;413.40469723598017;436.7248633360982;440.0736449773226;444.0280664773448;455.2829293862168;19763.209471621154;21521.24675636353;21550.56898862022;21985.23051693191;22014;20353.880536570483;21746;21896.45258868529;22326;22689.886550567266;5905.173275741399;6077.24034495688;6157.753689852423;6187.28846182688;6210;5899.941000590004;6015.789447369343;6034;6096.939030609681;6107.603005804608;5921.88156236875;6016.518678505709;6040.637561746287;6067.635941843481;6077.058056001305;4711.9162592603725;5509.531689806371;5675.460831221028;5989.401059894019;6000.519958403317;5281.947180528223;5303.36359636846;5338.359396872362;5348.438413966516;5349.411564727877;26919.461610767674;26934.73065269342;27027.918883244645;27030.783614737386;27058;2227.287268074217;2273.5680220758168;2294.4837411582425;2299.8275129365366;2305.4236440889777;16518.84368094234;17527.73708394375;17926.193321300572;17978.91010544952;17997.920124792556;2137.8075973162336;2162.7296587926508;2165.4153378587694;2179.2917301876955;2180.2805074325593;155404;155852;156642;157926;158967.2051639746;10404.791904161875;12210.267383956814;12372.505099795908;12413.627591172279;12478.75042499145;16172.676546469005;16257.349706011628;16302.429414970418;16340.83659163417;16341.303684892098;8631.19085658105;8871.467711937305;8966.462012279284;9077.954610227038;9124.133207345292;8490.745277641681;8527.53098579584;8528.445651032787;8632.309415246891;8717.825643487222;7618.771436856903;7696.230376962259;7721.729739459092;7755.92244077552;7770;2205.911763529473;2214.169686367612;2219.533897881463;2220.322801545512;2234.4860682043222;984.842425211977;990.6037584966072;999.1806718490913;1023.2859959567694;1045.0855501436242;20954.37136885896;21445.249416270668;21458.24896128628;21593.35219943404;21599.460013499473;942.731321573362;1022.1771473963382;1042.0777611813598;1042.801867645145;1059.3590877519143;9893.60425582983;9972.252081093948;10518.316309439862;10828.72928176786;10996.23026388145;546032;555113;556072;558663;559404;97163;122151;126682;130073;131615;1084.6203828659907;1181.1436708386352;1215.343714394235;1241.2862604002555;1246.246021157205;17660.293588256325;18382.448526544224;18394.908025459787;18422.447326580223;18798.812011880254;49518.75240623774;53645.92708145893;54687.45312546824;54765.726171368886;55819;75595.62202188953;75643.24356756582;76656.61671691606;76949;77386.61306693431;8604.78488037804;8633.69782057625;8726.859467057573;9032.32257580671;9049.909500904907;12888.162269452456;13912.860871391156;14046.087004345094;14116.294185290899;14274.714505710035;11628;11739.471723772622;11862.22895511789;11862.940685296518;11984.322194892897;11083.390413527333;11086.279391839505;11111.499982500969;11387.658370248906;11447.542098316308;11270.380129092651;11292.983631473207;11727.824082638597;11906.821397678868;12206;7353.485256032026;7455.552666840007;8743.606537705691;8847.24798392144;8884.73345799627;11650.30098194111;11826.704332391759;12075.335856527623;12093.334866582423;12167.391630418266 06:38:23 INFO - PID 1646 | |3;mootools.html;1354.532686223253;1427.8215223097113;1442.3365251984087;1449.0508716790503;1449.6086056764673;11137.665870023902;11276.830847537294;11613.361265130414;11614.767704645901;11633.476493557779;11323.886761132386;11417.25787823793;11495;11510.597129100483;11513.963743263104;2125.7236559247317;2595.195489398283;2598.5062838060803;2600.765931066204;2603.271084096456;2164.39396968849;2505.073122944513;2554.0932968796087;2561.180422264876;2561.8462892226435;2207.3157321230387;2448.9142879999204;2451.852888826676;2493.164789795421;2502.4119331957068;2443.2303824295377;2483.192962287255;2507.749225077496;2511.949761004788;2514.8365356251784;2491.8504889706674;2496.988719568578;2497.9250622481354;2503.474270403199;2611.6474275972882;11395;11478.94260528692;11487.885121148765;11502.654920352403;11530.250533715365;6517.543771935966;6678.599284042924;6741.292164322774;6745.527813053088;6777.356151165681;3978.80105994702;4364.934525982113;4415.558444155591;4424.469063712375;4444.20004399208;1234.8950339221176;1252.6930901929086;1272.9745405091846;1289.4648720780865;1290.3354772292278;12231.571894983634;15652.217389130612;15695;15712.842871571253;15840.366385344572;5390.568754499631;5462.6995515247045;5596.160575913564;5606.719664016783;5624.3250809903075;11220.326780393203;11289;11290.7177320566;11308.151888608387;11375;6379.489640828722;6410.61536307823;6463.547551671432;6468.3855033771715;6478;6001.339852616209;6079.848003799941;6114.144019837137;6193.535484838655;6253.6560489172625;4270.615644592001;4398.714083584557;4414.9845535526365;4451.755153466525;4464.950736576902;1290.6450726050412;1305.4125643460482;1315.4343632238229;1321.7819059855208;1329.454923481368;737.5390381011867;769.6151924037981;801.8235988082613;806.4838503357855;840.2647683277132;5634.492895639412;6536.411722944958;6897.310268973062;6899.0341352210735;6916.861662766716;889.3507739350181;905.4793493741125;913.5386629751932;923.0953665407937;939.7274790310734;3703.9073473324893;3923.6076392361106;4238.385434112037;4288.378185163134;4303.763293018851;14092.718145637029;14377.424903003972;14544.345504452325;14596.927015364856;14631.634209144644;13693.452261909411;14355.712885742436;14449.638759031108;14546.781798273034;14587.562373128823;8038.075621303508;8150.184981501802;8324.750257492286;8370.3303735701;8407.2433480987;6886.896696549656;7113.395361394132;7195.352418282379;7206.207317195209;7227.421806255382;12996.610101696964;13085.672858178621;13142.094396312672;13178.802317965046;13266.933665331611;13252.138610990254;13306.933465332611;13312;13323.733525329246;13674.606943784996;8985.101489850962;9286.117818807203;9288.489133097744;9292.442453452815;9295.581698823704;10380.532876020447;10386.272960892666;10409.32339397967;10477.16182705397;10512.632057877938;8929.10708929102;8945.73162805117;8954;9021.819563608822;9055.501947392957;8688.478691278544;8730.69442569507;8762.693305734269;8771.692990745294;8951.194392504445;7963.362930965393;8136.796580085545;8142.18578142181;8158.796030099057;8159.592020399075;5946.226990491208;5971.492423144082;6031.547633927561;6140.478059365004;6210.627362358273;6026.698665066641;6076.939230607637;6172.938270617416;6175.5677102602385;6343.714532846126 06:38:23 INFO - PID 1646 | |4;prototype.html;400.98997525061867;403.7678334957399;404.2561686496845;404.4236962328681;413.6256687697631;6012.458878700916;6193.165645458493;6246.81310550995;6264.84337891553;6266.84332891678;4575.8398456053965;4974.626902982272;5555.527795128045;5879.52963762899;6075.630504103403;4130.958690413087;4314.417553630251;4625.838095666652;4632.907341853161;4650.534946505356;3851.36452485341;4642.675012749109;4651.534846515338;4666.300054991744;4672.4393072831135;3957.891790298721;4452.933206001912;4569.200389931765;4598.793054312564;4620.838270660528;3752.6434988676033;4323.286657701476;4461.933071003938;4568.720758187713;4647.60495357895;4074.2462644410584;4082.1835632873544;4110.321796903538;4112.91774164518;4113.765870238946;4863.708177509326;4895.828645997409;5598.216249725042;5809.402414336049;6006.159137720722;5235.476452354772;5268.683878967236;5272.2355258487305;5286.60350473716;5290.68255904647;5437.592180586433;5612.887742245173;5929;6153.446189842891;6481.967590162066;4278.2085314216965;4291.849785257532;4293.957060429387;4307.440032795716;4341.2836881914445;5947.405259474018;6693.6318502482345;6828.951314605599;6895.275996020446;6928.891377379595;3595.280943811248;4002.6197511236387;4068.6948478864206;4363.887208761746;4401.625861801719;3791;3904.5314562252142;4438.533953934855;4455.6658250631335;4488.775561221926;4834.540718631724;5078.993797449986;5208.166693329125;5283.682979021271;5297.576193904479;6530.477561795045;6541.738330466728;6542.345765423514;6558;6562.475001999828;1268.2644066441626;1357.4773712120905;1451.5209980706552;1514.9318280677396;1534.4936171063746;844.953527555978;951.2707383415196;997.3816233935007;999.955046975912;1016.0855230292796;6305.96847015762;6326.398992095744;6342.936570634327;6350.6189628621505;6382.978723404233;6704.7988560343265;6827.21487028998;6836.863262734718;6878.65606719662;6889.931100689029;5011.899762004739;6187.504999600022;6190.071489276645;6241.906371404432;6282.089097080898;2902.883884644633;2998.595189649413;3384.0541688529192;3513.94729079064;3544.8759293424605;6483.2544257411;6747.359000894907;6918.069450268564;6948.895766563505;7057.435405167575;6132.938670613326;6978;6989.860202795916;7004.964975175193;7007.4394048476;6754.345185429601;6828.658567071627;7011.263817299212;7034.859302813813;7067.43460523157;5725.541956643542;6486.740530378732;6797.830054248683;6809.5233333666165;6815.284395138439;6366.4588509977175;6674.199096108498;6731.629760363227;6734.6632668367365;6743.898841517479;5144.536991670768;6449.097126402401;6918.927566227243;6967.442604591519;7114.466415018791;3622.6015138334237;4207.242696314693;4251.787410629394;4347.630496393625;4451.59935605797;5600.27196464457;6623.503237257129;6811.523193376416;6879.174499060145;6900.2409734928115;5988.341282458839;6583.48579826633;6609.239937407259;6616.106825578485;6630.778689139;5285.440794965418;5740.770369185186;6494.415502604788;6597.142371491867;6662.800115996528;6406.839829004312;6485.481161507162;6551.508636852351;6580.934190658032;6598.934010660024;6251.749370226624;6270.153529273673;6518.348165183444;6590.637514936724;6627.64718524936;6344.873102538016;6372.36276372359;6426.29310775805;6430.164078669743;6433.839154021187 06:38:23 INFO - PID 1646 | |5;yui.html;812.1310198088048;812.9878301514615;830.5930094253814;841.511923084611;843.974680759577;11910.702232444193;12206;14149.646258843533;14594.197319147443;16280;12941.676458088552;14645.487407940724;17014.574635634115;17121;17251.396201132964;1000.5197505197509;1021.780317231795;1021.954012069455;1022.2333250062453;1024;1007.098646711193;1009.6869970309227;1009.7728011197495;1010.2372708605029;1015.9136473399769;1051.5163025008505;1053.2837670384135;1055.0346433013822;1058.3861360410942;1061.4639606998455;1046.4401545173334;1055.5461151704765;1055.5724931402794;1062.617457715222;1068.3963560588281;782.6282515805004;786.5555960882141;788.6096382290802;790.4387884601939;792.9088154862206;16369.42707005261;16442.917785411115;16614.75077873833;16660.08369539675;16745.91627041857;15661.92169039159;16726.916365418096;16951.57621059471;17012.40456584026;17099.57251068721;13891.652708682364;14234.786478202835;14242;14384.568462946128;14484.637884052878;3368.2758206985372;3638.0904773806546;3658.432942893856;3669.9633003669887;3671.30245253401;98289.50855245821;110548.34177487264;111274.44362778215;112413.43793281063;112448.4377578107;13488.797668034988;13749;13756.587302380945;13770.036097473081;13803.654908627164;24871.378215544755;25371.11201107989;25525.87237063803;25919.611205831927;25942.35144121412;7634.122075961335;7634.770956871192;7732.497387669783;7774.961125194451;7785.610719464004;3314.1880239341517;3897.7661340319673;3919.4904662393706;3921.9607803922167;3927.4108883667677;4260.850870219528;4267.914641707149;4389.429374181336;4397.362382454527;4401.801918913657;915.2312057871287;933.5985526223789;949.0888746803147;953.1469334945185;955.7467271173144;626.9059641053879;646.5054233511368;647.9546431749732;649.6264647827518;680.7447207297264;9203.35576509638;11213.271137376045;11760.353180574979;11927.761444771057;11986.280823150639;638.1353266324156;641.2145122225237;641.4804008752925;642.7910928948111;675.625028109404;4321.481422229353;5422.6475279106735;5554.888902221933;5555.722213889289;5579.581531385163;86123;97270.05459890905;98315.16550962473;98384.50807745915;100369.49815250876;90436;92141.53929230446;92760.53619731858;92853;94089.5295523518;76662.61668691621;76679.61660191775;77119;77128.84306735403;77217.45565088667;11140.832887506533;11250.549978000789;11258.605948791754;11265.71835704114;11421.200515964136;39197;39911.40132897951;40010.599629012846;40178;40376.19247614973;50768;50768.74615627046;51250;51355;51890.74054629854;13593.32033398346;13629.795553066513;13976.930115349358;14041.946853986195;14415.495457658932;13120.934395327962;14924.029938053991;15201.695966080837;15661.060336379398;15758.369665213264;13946.79079813824;14410.135391876522;14415.189038137463;14552;14658.78011829802;14198.219097949712;14457.710845783236;14475.927620362252;14501.347439365043;14620.342084606005;13462.326883655975;13967;14108.647283817987;14335.78496322577;14366.856331436551;10290;10494.632687855888;10497.527611257665;10640.255182137485;10738.463076846283;9240.41971942109;9770.75573110678;11412.885871141183;11854.229475084092;11953 06:38:23 INFO - PID 1646 | __end_tp_report 06:38:23 INFO - PID 1646 | __start_cc_report 06:38:23 INFO - PID 1646 | _x_x_mozilla_cycle_collect,340 06:38:23 INFO - PID 1646 | __end_cc_report 06:38:23 INFO - PID 1646 | __startTimestamp1494855503232__endTimestamp 06:38:23 INFO - PID 1646 | 06:38:23 INFO - PID 1646 | ------- Summary: start ------- 06:38:23 INFO - PID 1646 | Number of tests: 6 06:38:23 INFO - PID 1646 | 06:38:23 INFO - PID 1646 | [#0] dojo.html Cycles:165 Average:6101.95 Median:6369.14 stddev:775.65 (12.2%) stddev-sans-first:732.94 06:38:23 INFO - PID 1646 | Values: 2779.9 3410.9 3579.4 3633.1 3639.3 6074.7 6181.1 6202.8 6218.0 6262.8 6142.7 6160.9 6201.2 6206.2 6262.7 5407.1 5445.4 5447.3 5451.9 5458.3 5494.6 5548.4 5553.3 5566.5 5583.6 5347.1 5402.8 5440.1 5487.7 5549.1 5461.9 5475.6 5515.9 5529.2 5543.6 5244.8 5441.9 5466.7 5467.4 5502.6 4050.8 5400.8 5567.6 5792.5 6142.5 6381.6 6450.5 6548.7 6681.6 6747.5 5210.7 5534.7 6784.2 6789.2 6813.7 6152.9 6270.6 6304.9 6322.1 6329.9 6694.3 6820.7 6926.7 6927.8 6958.2 6508.0 6561.7 6595.5 6603.7 6634.9 5095.6 6766.8 6767.2 6793.7 6817.3 5599.9 6460.5 6482.0 6505.6 6602.8 6703.3 6719.3 6725.1 6730.6 6754.3 5194.3 5205.7 5234.9 5244.8 5306.2 4719.2 4760.5 4782.0 4853.6 4864.0 5171.8 6371.1 6418.0 6437.4 6448.1 5620.3 6822.3 6823.9 6849.2 6853.2 6255.3 6315.9 6334.9 6443.6 6467.2 5801.5 5885.2 5943.8 5954.4 5978.6 6927.3 6937.1 6995.1 6996.5 7017.6 5886.8 6806.6 6813.3 6831.7 6853.1 5591.3 6693.9 6820.2 6854.0 7024.4 6600.5 6672.0 6702.9 6708.2 6715.6 6755.8 6768.4 6787.1 6814.8 6815.1 6356.8 6496.8 6543.7 6589.6 6613.3 6518.3 6549.5 6575.0 6587.0 6591.9 5621.8 6040.6 6241.4 6300.1 6421.9 6430.2 6449.6 6520.2 6610.8 6625.7 5853.2 6369.1 6434.7 6448.7 6461.4 06:38:23 INFO - PID 1646 | 06:38:23 INFO - PID 1646 | [#1] ext.html Cycles:180 Average:44058.08 Median:18358.92 stddev:74250.17 (404.4%) stddev-sans-first:74387.42 06:38:23 INFO - PID 1646 | Values: 798.1 811.0 831.6 1034.4 1191.5 38388.2 39461.4 39482.0 39544.8 39626.0 39170.6 39418.8 39491.0 39628.0 39654.2 7936.1 8347.3 8537.7 8538.2 8547.0 7422.9 8528.9 8542.2 8617.9 8627.4 7408.2 8485.2 8575.7 8588.0 8590.1 8522.5 8544.6 8546.6 8572.7 8576.9 8394.0 8458.2 8465.7 8471.9 8474.7 37097.6 37136.6 37188.0 37190.7 37493.0 3693.4 3693.5 3694.9 3725.2 3734.2 30392.2 30527.2 30648.7 30654.8 30717.8 3141.5 3188.4 3191.7 3196.6 3203.5 252140.0 257180.0 282552.0 293713.0 296127.0 48499.8 48673.3 48793.3 48798.0 48882.0 51046.7 51051.5 51070.5 51100.2 51145.7 13943.8 14021.7 14078.7 14097.6 14107.9 11563.5 11576.1 11587.1 11596.8 11622.7 13574.4 13629.5 13772.7 13854.7 13923.7 6159.1 6248.9 6257.3 6261.4 6278.9 1021.5 1035.7 1043.7 1075.3 1093.4 24104.8 25117.5 25260.7 25275.2 25297.1 931.9 1103.4 1179.3 1179.5 1190.5 10900.4 12363.1 12771.8 12902.4 12905.9 314154.0 316837.0 316846.0 317301.0 317613.0 54151.7 54615.2 54713.7 54766.5 54847.0 1559.6 1585.6 1608.7 1609.2 1634.5 24449.1 24582.0 24644.5 24711.1 24735.0 38748.6 49138.8 49236.8 49251.8 49336.0 252852.0 254004.0 254152.0 254537.0 254679.0 33514.3 33903.5 34973.0 35207.1 35381.3 42612.4 43559.6 45671.5 45751.8 46257.3 27790.6 29313.4 30779.7 31420.7 31442.7 28228.9 30520.1 31029.0 31699.2 31866.7 36575.1 36998.6 37552.4 37824.1 37880.8 26254.9 27289.6 30129.2 30317.8 30477.0 17417.7 24435.0 24571.9 24924.1 24938.1 06:38:23 INFO - PID 1646 | 06:38:23 INFO - PID 1646 | [#2] jquery.html Cycles:180 Average:35335.96 Median:12276.64 stddev:93955.48 (765.3%) stddev-sans-first:94182.46 06:38:23 INFO - PID 1646 | Values: 413.4 436.7 440.1 444.0 455.3 19763.2 21521.2 21550.6 21985.2 22014.0 20353.9 21746.0 21896.5 22326.0 22689.9 5905.2 6077.2 6157.8 6187.3 6210.0 5899.9 6015.8 6034.0 6096.9 6107.6 5921.9 6016.5 6040.6 6067.6 6077.1 4711.9 5509.5 5675.5 5989.4 6000.5 5281.9 5303.4 5338.4 5348.4 5349.4 26919.5 26934.7 27027.9 27030.8 27058.0 2227.3 2273.6 2294.5 2299.8 2305.4 16518.8 17527.7 17926.2 17978.9 17997.9 2137.8 2162.7 2165.4 2179.3 2180.3 155404.0 155852.0 156642.0 157926.0 158967.2 10404.8 12210.3 12372.5 12413.6 12478.8 16172.7 16257.3 16302.4 16340.8 16341.3 8631.2 8871.5 8966.5 9078.0 9124.1 8490.7 8527.5 8528.4 8632.3 8717.8 7618.8 7696.2 7721.7 7755.9 7770.0 2205.9 2214.2 2219.5 2220.3 2234.5 984.8 990.6 999.2 1023.3 1045.1 20954.4 21445.2 21458.2 21593.4 21599.5 942.7 1022.2 1042.1 1042.8 1059.4 9893.6 9972.3 10518.3 10828.7 10996.2 546032.0 555113.0 556072.0 558663.0 559404.0 97163.0 122151.0 126682.0 130073.0 131615.0 1084.6 1181.1 1215.3 1241.3 1246.2 17660.3 18382.4 18394.9 18422.4 18798.8 49518.8 53645.9 54687.5 54765.7 55819.0 75595.6 75643.2 76656.6 76949.0 77386.6 8604.8 8633.7 8726.9 9032.3 9049.9 12888.2 13912.9 14046.1 14116.3 14274.7 11628.0 11739.5 11862.2 11862.9 11984.3 11083.4 11086.3 11111.5 11387.7 11447.5 11270.4 11293.0 11727.8 11906.8 12206.0 7353.5 7455.6 8743.6 8847.2 8884.7 11650.3 11826.7 12075.3 12093.3 12167.4 06:38:23 INFO - PID 1646 | 06:38:23 INFO - PID 1646 | [#3] mootools.html Cycles:180 Average:6984.49 Median:3813.76 stddev:4296.43 (112.7%) stddev-sans-first:4287.65 06:38:23 INFO - PID 1646 | Values: 1354.5 1427.8 1442.3 1449.1 1449.6 11137.7 11276.8 11613.4 11614.8 11633.5 11323.9 11417.3 11495.0 11510.6 11514.0 2125.7 2595.2 2598.5 2600.8 2603.3 2164.4 2505.1 2554.1 2561.2 2561.8 2207.3 2448.9 2451.9 2493.2 2502.4 2443.2 2483.2 2507.7 2511.9 2514.8 2491.9 2497.0 2497.9 2503.5 2611.6 11395.0 11478.9 11487.9 11502.7 11530.3 6517.5 6678.6 6741.3 6745.5 6777.4 3978.8 4364.9 4415.6 4424.5 4444.2 1234.9 1252.7 1273.0 1289.5 1290.3 12231.6 15652.2 15695.0 15712.8 15840.4 5390.6 5462.7 5596.2 5606.7 5624.3 11220.3 11289.0 11290.7 11308.2 11375.0 6379.5 6410.6 6463.5 6468.4 6478.0 6001.3 6079.8 6114.1 6193.5 6253.7 4270.6 4398.7 4415.0 4451.8 4465.0 1290.6 1305.4 1315.4 1321.8 1329.5 737.5 769.6 801.8 806.5 840.3 5634.5 6536.4 6897.3 6899.0 6916.9 889.4 905.5 913.5 923.1 939.7 3703.9 3923.6 4238.4 4288.4 4303.8 14092.7 14377.4 14544.3 14596.9 14631.6 13693.5 14355.7 14449.6 14546.8 14587.6 8038.1 8150.2 8324.8 8370.3 8407.2 6886.9 7113.4 7195.4 7206.2 7227.4 12996.6 13085.7 13142.1 13178.8 13266.9 13252.1 13306.9 13312.0 13323.7 13674.6 8985.1 9286.1 9288.5 9292.4 9295.6 10380.5 10386.3 10409.3 10477.2 10512.6 8929.1 8945.7 8954.0 9021.8 9055.5 8688.5 8730.7 8762.7 8771.7 8951.2 7963.4 8136.8 8142.2 8158.8 8159.6 5946.2 5971.5 6031.5 6140.5 6210.6 6026.7 6076.9 6172.9 6175.6 6343.7 06:38:23 INFO - PID 1646 | 06:38:23 INFO - PID 1646 | [#4] prototype.html Cycles:180 Average:5258.53 Median:6009.31 stddev:1695.56 (28.2%) stddev-sans-first:1660.66 06:38:23 INFO - PID 1646 | Values: 401.0 403.8 404.3 404.4 413.6 6012.5 6193.2 6246.8 6264.8 6266.8 4575.8 4974.6 5555.5 5879.5 6075.6 4131.0 4314.4 4625.8 4632.9 4650.5 3851.4 4642.7 4651.5 4666.3 4672.4 3957.9 4452.9 4569.2 4598.8 4620.8 3752.6 4323.3 4461.9 4568.7 4647.6 4074.2 4082.2 4110.3 4112.9 4113.8 4863.7 4895.8 5598.2 5809.4 6006.2 5235.5 5268.7 5272.2 5286.6 5290.7 5437.6 5612.9 5929.0 6153.4 6482.0 4278.2 4291.8 4294.0 4307.4 4341.3 5947.4 6693.6 6829.0 6895.3 6928.9 3595.3 4002.6 4068.7 4363.9 4401.6 3791.0 3904.5 4438.5 4455.7 4488.8 4834.5 5079.0 5208.2 5283.7 5297.6 6530.5 6541.7 6542.3 6558.0 6562.5 1268.3 1357.5 1451.5 1514.9 1534.5 845.0 951.3 997.4 1000.0 1016.1 6306.0 6326.4 6342.9 6350.6 6383.0 6704.8 6827.2 6836.9 6878.7 6889.9 5011.9 6187.5 6190.1 6241.9 6282.1 2902.9 2998.6 3384.1 3513.9 3544.9 6483.3 6747.4 6918.1 6948.9 7057.4 6132.9 6978.0 6989.9 7005.0 7007.4 6754.3 6828.7 7011.3 7034.9 7067.4 5725.5 6486.7 6797.8 6809.5 6815.3 6366.5 6674.2 6731.6 6734.7 6743.9 5144.5 6449.1 6918.9 6967.4 7114.5 3622.6 4207.2 4251.8 4347.6 4451.6 5600.3 6623.5 6811.5 6879.2 6900.2 5988.3 6583.5 6609.2 6616.1 6630.8 5285.4 5740.8 6494.4 6597.1 6662.8 6406.8 6485.5 6551.5 6580.9 6598.9 6251.7 6270.2 6518.3 6590.6 6627.6 6344.9 6372.4 6426.3 6430.2 6433.8 06:38:23 INFO - PID 1646 | 06:38:23 INFO - PID 1646 | [#5] yui.html Cycles:180 Average:20288.22 Median:16848.75 stddev:28333.48 (168.2%) stddev-sans-first:28375.22 06:38:23 INFO - PID 1646 | Values: 812.1 813.0 830.6 841.5 844.0 11910.7 12206.0 14149.6 14594.2 16280.0 12941.7 14645.5 17014.6 17121.0 17251.4 1000.5 1021.8 1022.0 1022.2 1024.0 1007.1 1009.7 1009.8 1010.2 1015.9 1051.5 1053.3 1055.0 1058.4 1061.5 1046.4 1055.5 1055.6 1062.6 1068.4 782.6 786.6 788.6 790.4 792.9 16369.4 16442.9 16614.8 16660.1 16745.9 15661.9 16726.9 16951.6 17012.4 17099.6 13891.7 14234.8 14242.0 14384.6 14484.6 3368.3 3638.1 3658.4 3670.0 3671.3 98289.5 110548.3 111274.4 112413.4 112448.4 13488.8 13749.0 13756.6 13770.0 13803.7 24871.4 25371.1 25525.9 25919.6 25942.4 7634.1 7634.8 7732.5 7775.0 7785.6 3314.2 3897.8 3919.5 3922.0 3927.4 4260.9 4267.9 4389.4 4397.4 4401.8 915.2 933.6 949.1 953.1 955.7 626.9 646.5 648.0 649.6 680.7 9203.4 11213.3 11760.4 11927.8 11986.3 638.1 641.2 641.5 642.8 675.6 4321.5 5422.6 5554.9 5555.7 5579.6 86123.0 97270.1 98315.2 98384.5 100369.5 90436.0 92141.5 92760.5 92853.0 94089.5 76662.6 76679.6 77119.0 77128.8 77217.5 11140.8 11250.5 11258.6 11265.7 11421.2 39197.0 39911.4 40010.6 40178.0 40376.2 50768.0 50768.7 51250.0 51355.0 51890.7 13593.3 13629.8 13976.9 14041.9 14415.5 13120.9 14924.0 15201.7 15661.1 15758.4 13946.8 14410.1 14415.2 14552.0 14658.8 14198.2 14457.7 14475.9 14501.3 14620.3 13462.3 13967.0 14108.6 14335.8 14366.9 10290.0 10494.6 10497.5 10640.3 10738.5 9240.4 9770.8 11412.9 11854.2 11953.0 06:38:23 INFO - PID 1646 | -------- Summary: end -------- 06:38:23 INFO - PID 1646 | 06:38:23 INFO - TEST-INFO | 1646: exit 0 06:38:23 INFO - TEST-OK | dromaeo_css | took 1114154ms 06:38:23 INFO - TEST-START | kraken 06:38:23 INFO - operating with platform_type : mac_ 06:38:23 INFO - Initialising browser for kraken test... 06:38:23 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/tmpBhbMSm/profile http://localhost:49227/getInfo.html) 06:38:24 INFO - PID 1661 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2932: String.localeCompare is deprecated; use String.prototype.localeCompare instead 06:38:26 INFO - PID 1661 | 2017-05-15 06:38:26.099 plugin-container[1662:10396] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x473b, name = 'com.apple.tsm.portname' 06:38:26 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:38:26 INFO - PID 1661 | 2017-05-15 06:38:26.103 plugin-container[1662:10396] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 06:38:26 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:38:26 INFO - PID 1661 | 2017-05-15 06:38:26.103 plugin-container[1662:10396] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:38:26 INFO - PID 1661 | __metrics Screen width/height:1600/1200 06:38:26 INFO - PID 1661 | colorDepth:24 06:38:26 INFO - PID 1661 | Browser inner width/height: 1024/691 06:38:26 INFO - PID 1661 | __metrics 06:38:26 INFO - TEST-INFO | 1661: exit 0 06:38:26 INFO - Browser initialized. 06:38:26 INFO - Running cycle 1/1 for kraken test... 06:38:26 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/tmpBhbMSm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:38:28 INFO - PID 1663 | 2017-05-15 06:38:28.608 plugin-container[1664:10506] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x473f, name = 'com.apple.tsm.portname' 06:38:28 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:38:28 INFO - PID 1663 | 2017-05-15 06:38:28.608 plugin-container[1664:10506] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4743, name = 'com.apple.CFPasteboardClient' 06:38:28 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:38:28 INFO - PID 1663 | 2017-05-15 06:38:28.608 plugin-container[1664:10506] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:38:29 INFO - PID 1663 | 2017-05-15 06:38:29.384 plugin-container[1665:10553] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 06:38:29 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:38:29 INFO - PID 1663 | 2017-05-15 06:38:29.384 plugin-container[1665:10553] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 06:38:29 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:38:29 INFO - PID 1663 | 2017-05-15 06:38:29.384 plugin-container[1665:10553] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:38:38 INFO - PID 1663 | RSS: Main: 234999808 06:38:38 INFO - PID 1663 | 06:38:41 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection) 06:38:42 INFO - PID 1663 | RSS: Main: 247775232 06:38:42 INFO - PID 1663 | 06:38:44 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-dft) 06:38:45 INFO - PID 1663 | RSS: Main: 256335872 06:38:45 INFO - PID 1663 | 06:38:48 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-fft) 06:38:49 INFO - PID 1663 | RSS: Main: 257839104 06:38:49 INFO - PID 1663 | 06:38:51 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator) 06:38:51 INFO - PID 1663 | RSS: Main: 259985408 06:38:51 INFO - PID 1663 | 06:38:53 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:38:54 INFO - PID 1663 | RSS: Main: 260775936 06:38:54 INFO - PID 1663 | 06:38:57 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom) 06:38:57 INFO - PID 1663 | RSS: Main: 266723328 06:38:57 INFO - PID 1663 | 06:39:01 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate) 06:39:01 INFO - PID 1663 | RSS: Main: 266813440 06:39:01 INFO - PID 1663 | 06:39:04 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial) 06:39:05 INFO - PID 1663 | RSS: Main: 265973760 06:39:05 INFO - PID 1663 | 06:39:06 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:39:07 INFO - PID 1663 | RSS: Main: 265273344 06:39:07 INFO - PID 1663 | 06:39:09 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:39:09 INFO - PID 1663 | RSS: Main: 265551872 06:39:09 INFO - PID 1663 | 06:39:11 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:39:12 INFO - PID 1663 | RSS: Main: 265576448 06:39:12 INFO - PID 1663 | 06:39:15 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:39:16 INFO - PID 1663 | RSS: Main: 265453568 06:39:16 INFO - PID 1663 | 06:39:19 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:39:19 INFO - PID 1663 | RSS: Main: 265551872 06:39:19 INFO - PID 1663 | 06:39:21 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49227/tests/kraken/driver.html?testName=ai-astar) 06:39:21 INFO - PID 1663 | RSS: Main: 267534336 06:39:21 INFO - PID 1663 | 06:39:21 INFO - PID 1663 | __start_tp_report 06:39:21 INFO - PID 1663 | _x_x_mozilla_page_load 06:39:21 INFO - PID 1663 | _x_x_mozilla_page_load_details 06:39:21 INFO - PID 1663 | |i|pagename|runs| 06:39:21 INFO - PID 1663 | |0;ai-astar;104;115;114;114;115;115;115;114;116;115 06:39:21 INFO - PID 1663 | |1;audio-beat-detection;132;112;110;145;111;106;112;102;162;93 06:39:21 INFO - PID 1663 | |2;audio-dft;194;185;217;238;198;214;243;204;284;281 06:39:21 INFO - PID 1663 | |3;audio-fft;85;55;58;79;59;58;62;69;91;47 06:39:21 INFO - PID 1663 | |4;audio-oscillator;85;69;83;72;69;72;88;63;65;88 06:39:21 INFO - PID 1663 | |5;imaging-gaussian-blur;90;90;92;88;88;88;89;90;88;88 06:39:21 INFO - PID 1663 | |6;imaging-darkroom;96;97;96;96;96;97;125;150;114;97 06:39:21 INFO - PID 1663 | |7;imaging-desaturate;91;93;88;88;92;89;90;93;91;91 06:39:21 INFO - PID 1663 | |8;json-parse-financial;50;48;47;48;48;50;52;52;66;74 06:39:21 INFO - PID 1663 | |9;json-stringify-tinderbox;61;58;69;57;68;65;58;56;55;58 06:39:21 INFO - PID 1663 | |10;stanford-crypto-aes;85;81;102;101;104;108;85;84;93;74 06:39:21 INFO - PID 1663 | |11;stanford-crypto-ccm;134;133;157;160;148;138;142;140;185;139 06:39:21 INFO - PID 1663 | |12;stanford-crypto-pbkdf2;201;182;188;183;182;184;185;186;181;184 06:39:21 INFO - PID 1663 | |13;stanford-crypto-sha256-iterative;100;100;94;100;103;78;70;68;69;70 06:39:21 INFO - PID 1663 | __end_tp_report 06:39:21 INFO - PID 1663 | __start_cc_report 06:39:21 INFO - PID 1663 | _x_x_mozilla_cycle_collect,935 06:39:21 INFO - PID 1663 | __end_cc_report 06:39:21 INFO - PID 1663 | __startTimestamp1494855561979__endTimestamp 06:39:21 INFO - PID 1663 | 06:39:21 INFO - PID 1663 | ------- Summary: start ------- 06:39:21 INFO - PID 1663 | Number of tests: 14 06:39:21 INFO - PID 1663 | 06:39:21 INFO - PID 1663 | [#0] ai-astar Cycles:10 Average:113.70 Median:115.00 stddev:3.47 (3.0%) stddev-sans-first:0.67 06:39:21 INFO - PID 1663 | Values: 104.0 115.0 114.0 114.0 115.0 115.0 115.0 114.0 116.0 115.0 06:39:21 INFO - PID 1663 | 06:39:21 INFO - PID 1663 | [#1] audio-beat-detection Cycles:10 Average:118.50 Median:122.00 stddev:21.26 (17.4%) stddev-sans-first:21.98 06:39:21 INFO - PID 1663 | Values: 132.0 112.0 110.0 145.0 111.0 106.0 112.0 102.0 162.0 93.0 06:39:21 INFO - PID 1663 | 06:39:21 INFO - PID 1663 | [#2] audio-dft Cycles:10 Average:225.80 Median:227.50 stddev:34.99 (15.4%) stddev-sans-first:35.17 06:39:21 INFO - PID 1663 | Values: 194.0 185.0 217.0 238.0 198.0 214.0 243.0 204.0 284.0 281.0 06:39:21 INFO - PID 1663 | 06:39:21 INFO - PID 1663 | [#3] audio-fft Cycles:10 Average:66.30 Median:65.50 stddev:14.29 (21.8%) stddev-sans-first:13.46 06:39:21 INFO - PID 1663 | Values: 85.0 55.0 58.0 79.0 59.0 58.0 62.0 69.0 91.0 47.0 06:39:21 INFO - PID 1663 | 06:39:21 INFO - PID 1663 | [#4] audio-oscillator Cycles:10 Average:75.40 Median:77.50 stddev:9.63 (12.4%) stddev-sans-first:9.57 06:39:21 INFO - PID 1663 | Values: 85.0 69.0 83.0 72.0 69.0 72.0 88.0 63.0 65.0 88.0 06:39:21 INFO - PID 1663 | 06:39:21 INFO - PID 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:89.10 Median:89.50 stddev:1.37 (1.5%) stddev-sans-first:1.41 06:39:21 INFO - PID 1663 | Values: 90.0 90.0 92.0 88.0 88.0 88.0 89.0 90.0 88.0 88.0 06:39:21 INFO - PID 1663 | 06:39:21 INFO - PID 1663 | [#6] imaging-darkroom Cycles:10 Average:106.40 Median:96.00 stddev:18.26 (19.0%) stddev-sans-first:18.98 06:39:21 INFO - PID 1663 | Values: 96.0 97.0 96.0 96.0 96.0 97.0 125.0 150.0 114.0 97.0 06:39:21 INFO - PID 1663 | 06:39:21 INFO - PID 1663 | [#7] imaging-desaturate Cycles:10 Average:90.60 Median:91.00 stddev:1.84 (2.0%) stddev-sans-first:1.94 06:39:21 INFO - PID 1663 | Values: 91.0 93.0 88.0 88.0 92.0 89.0 90.0 93.0 91.0 91.0 06:39:21 INFO - PID 1663 | 06:39:21 INFO - PID 1663 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:51.00 stddev:9.06 (17.8%) stddev-sans-first:9.52 06:39:21 INFO - PID 1663 | Values: 50.0 48.0 47.0 48.0 48.0 50.0 52.0 52.0 66.0 74.0 06:39:21 INFO - PID 1663 | 06:39:21 INFO - PID 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:60.50 Median:59.50 stddev:5.06 (8.5%) stddev-sans-first:5.36 06:39:21 INFO - PID 1663 | Values: 61.0 58.0 69.0 57.0 68.0 65.0 58.0 56.0 55.0 58.0 06:39:21 INFO - PID 1663 | 06:39:21 INFO - PID 1663 | [#10] stanford-crypto-aes Cycles:10 Average:91.70 Median:82.50 stddev:11.49 (13.9%) stddev-sans-first:11.93 06:39:21 INFO - PID 1663 | Values: 85.0 81.0 102.0 101.0 104.0 108.0 85.0 84.0 93.0 74.0 06:39:21 INFO - PID 1663 | 06:39:21 INFO - PID 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:147.60 Median:145.00 stddev:15.97 (11.0%) stddev-sans-first:16.16 06:39:21 INFO - PID 1663 | Values: 134.0 133.0 157.0 160.0 148.0 138.0 142.0 140.0 185.0 139.0 06:39:21 INFO - PID 1663 | 06:39:21 INFO - PID 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.60 Median:184.50 stddev:5.80 (3.1%) stddev-sans-first:2.20 06:39:21 INFO - PID 1663 | Values: 201.0 182.0 188.0 183.0 182.0 184.0 185.0 186.0 181.0 184.0 06:39:21 INFO - PID 1663 | 06:39:21 INFO - PID 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:85.20 Median:69.50 stddev:15.36 (22.1%) stddev-sans-first:15.33 06:39:21 INFO - PID 1663 | Values: 100.0 100.0 94.0 100.0 103.0 78.0 70.0 68.0 69.0 70.0 06:39:21 INFO - PID 1663 | -------- Summary: end -------- 06:39:21 INFO - PID 1663 | 06:39:22 INFO - TEST-INFO | 1663: exit 0 06:39:23 INFO - TEST-OK | kraken | took 59169ms 06:39:23 INFO - SUITE-END | took 1173s 06:39:23 INFO - Completed test suite (00:19:33) 06:39:23 INFO - javascript benchmark 06:39:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7786.271387300497, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2779.874150968857, 3410.931781364373, 3579.355715971126, 3633.091727068233, 3639.344917914774, 6074.726637301321, 6181.072839074135, 6202.751889924403, 6217.968910155449, 6262.812115636527, 6142.652254521286, 6160.876782464359, 6201.193844800181, 6206.193194884659, 6262.718177682003, 5407.080796264644, 5445.37378201506, 5447.346318441772, 5451.890962180754, 5458.263134476854, 5494.560435165177, 5548.4174161713045, 5553.305836770403, 5566.471185237396, 5583.637063590874, 5347.064263753867, 5402.837914862561, 5440.075187218183, 5487.6981766002855, 5549.084401073818, 5461.890762184774, 5475.561955043587, 5515.889682206373, 5529.170624406331, 5543.6119471637, 5244.81643142492, 5441.918371224435, 5466.671999680032, 5467.425920278353, 5502.5597952163735, 4050.797460127011, 5400.83797486076, 5567.582431317667, 5792.507636850831, 6142.539309551757, 6381.553291269613, 6450.4839612830865, 6548.70530826114, 6681.556670090014, 6747.460203183734, 5210.687358758449, 5534.667919924817, 6784.151981002375, 6789.15135608049, 6813.659317034128, 6152.8769424611255, 6270.561060725751, 6304.905426418607, 6322.146510221107, 6329.936700632981, 6694.263631000586, 6820.727170913207, 6926.688299026556, 6927.826804329932, 6958.199807022156, 6508.0, 6561.671916404161, 6595.472362211011, 6603.70283337251, 6634.900476492857, 5095.643304968691, 6766.763163289262, 6767.154105736783, 6793.66031698423, 6817.318268173242, 5599.880023995217, 6460.45086167681, 6482.0, 6505.609663420115, 6602.778485980013, 6703.3296670332575, 6719.294474080249, 6725.05849181115, 6730.562513436611, 6754.257031726506, 5194.27280180775, 5205.7397130143345, 5234.895302093937, 5244.816431424882, 5306.177542480922, 4719.197736384823, 4760.547747963938, 4781.95218047822, 4853.587445067209, 4864.027194561102, 5171.844844654666, 6371.108044873721, 6417.96791016042, 6437.420632143129, 6448.065030570541, 5620.269364982608, 6822.283660215705, 6823.863522729517, 6849.1780986281965, 6853.246142924274, 6255.311915689271, 6315.905261421082, 6334.904976425358, 6443.581167224115, 6467.223933127961, 5801.53587712982, 5885.2349194605285, 5943.821685349447, 5954.434328738763, 5978.5814992950945, 6927.307269273234, 6937.098177236927, 6995.055667484824, 6996.545224560591, 7017.649117544001, 5886.764529418947, 6806.591604503746, 6813.318668133147, 6831.726730930707, 6853.143357080365, 5591.30108736408, 6693.866122677617, 6820.181578210647, 6854.0404343390965, 7024.402925751164, 6600.471962242913, 6671.966640166768, 6702.865942681216, 6708.161479815023, 6715.630640314829, 6755.7973260802255, 6768.357006084414, 6787.117674702455, 6814.761483348059, 6815.079964204769, 6356.841078973062, 6496.837579060561, 6543.738250469928, 6589.571677840925, 6613.338666133348, 6518.282988871126, 6549.508786841101, 6574.980877963923, 6587.011948207807, 6591.868162636816, 5621.775128994795, 6040.607360521552, 6241.438270555672, 6300.086487459201, 6421.8715625688155, 6430.196225471816, 6449.613023218434, 6520.152380190545, 6610.8016759497295, 6625.734970601122, 5853.151293062397, 6369.1401660777055, 6434.742610295723, 6448.709808058742, 6461.418472337512], "value": 6051.43737709, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [798.0901771979944, 811.0, 831.56342919967, 1034.3742036068174, 1191.4817054581258, 38388.23223535531, 39461.40807887884, 39482.012949676275, 39544.802275988615, 39626.00934976627, 39170.60829391705, 39418.81743547691, 39491.0, 39628.0, 39654.206915861665, 7936.127026027132, 8347.29048030918, 8537.694822397949, 8538.231559159674, 8547.0, 7422.888656670154, 8528.914710852905, 8542.188492093272, 8617.913820861806, 8627.352948528855, 7408.185099639036, 8485.151484851527, 8575.742727718178, 8588.0, 8590.055093939662, 8522.488650680947, 8544.61549230283, 8546.572671366406, 8572.742817715478, 8576.914230857705, 8394.034686011126, 8458.238758511763, 8465.703700370519, 8471.872921906177, 8474.703385381483, 37097.62902370969, 37136.62863371386, 37188.0, 37190.69832555875, 37493.0, 3693.3905905525558, 3693.501377314055, 3694.907627309312, 3725.217704282104, 3734.159814041846, 30392.240193995105, 30527.236819079477, 30648.693513064805, 30654.846725766452, 30717.846410768027, 3141.528770684393, 3188.4420226460275, 3191.712745852861, 3196.6483686794436, 3203.5194720791833, 252140.0, 257180.0, 282552.0, 293713.0, 296127.0, 48499.757501212975, 48673.26990095151, 48793.268100978516, 48798.02403951972, 48882.0, 51046.7238319045, 51051.48948510467, 51070.48929510657, 51100.23349649758, 51145.7442712784, 13943.790843137362, 14021.719565608631, 14078.71842563143, 14097.577072687836, 14107.929460352632, 11563.537458501734, 11576.131790115774, 11587.130965177645, 11596.826047609293, 11622.6513204604, 13574.389152488162, 13629.522966696317, 13772.724545509034, 13854.65363365924, 13923.65190870216, 6159.137720719103, 6248.875022499525, 6257.342978987232, 6261.373862613793, 6278.937210627835, 1021.545412291538, 1035.7358873487212, 1043.7181960870523, 1075.295681328731, 1093.4478088565384, 24104.75895241025, 25117.497650047262, 25260.747392526206, 25275.24174274775, 25297.114600988876, 931.9347645664874, 1103.4372470040337, 1179.2747460311846, 1179.5281887244998, 1190.4642910690225, 10900.40047797363, 12363.072769542321, 12771.808422873664, 12902.35488225585, 12905.870941290656, 314154.0, 316837.0, 316846.0, 317301.0, 317613.0, 54151.729241354325, 54615.18077228845, 54713.726431368385, 54766.45233547613, 54847.0, 1559.5711179425523, 1585.58774718574, 1608.702390057843, 1609.1954022988505, 1634.5014770494874, 24449.144279950116, 24582.016719331026, 24644.50710985806, 24711.135110271774, 24735.010599576537, 38748.6125138745, 49138.75430622824, 49236.75381623212, 49251.75374123106, 49336.0, 252852.0, 254004.0, 254152.0, 254537.0, 254679.0, 33514.329713406085, 33903.491447628796, 34973.0, 35207.11982200466, 35381.29237415186, 42612.360814587184, 43559.56440435555, 45671.54328456673, 45751.77124114491, 46257.30614040725, 27790.583141252493, 29313.41373172482, 30779.692203077684, 31420.685793141776, 31442.685573143975, 28228.85885570641, 30520.084397468112, 31029.0, 31699.20751981219, 31866.681333187298, 36575.08562285964, 36998.6300137006, 37552.41155627982, 37824.05439863916, 37880.81059594794, 26254.868725656248, 27289.590656139775, 30129.24676883008, 30317.84841075869, 30477.0, 17417.738733918748, 24435.0, 24571.877140614182, 24924.12765553197, 24938.12716554912], "value": 17699.822032313452, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [413.40469723598017, 436.7248633360982, 440.0736449773226, 444.0280664773448, 455.2829293862168, 19763.209471621154, 21521.24675636353, 21550.56898862022, 21985.23051693191, 22014.0, 20353.880536570483, 21746.0, 21896.45258868529, 22326.0, 22689.886550567266, 5905.173275741399, 6077.24034495688, 6157.753689852423, 6187.28846182688, 6210.0, 5899.941000590004, 6015.789447369343, 6034.0, 6096.939030609681, 6107.603005804608, 5921.88156236875, 6016.518678505709, 6040.637561746287, 6067.635941843481, 6077.058056001305, 4711.9162592603725, 5509.531689806371, 5675.460831221028, 5989.401059894019, 6000.519958403317, 5281.947180528223, 5303.36359636846, 5338.359396872362, 5348.438413966516, 5349.411564727877, 26919.461610767674, 26934.73065269342, 27027.918883244645, 27030.783614737386, 27058.0, 2227.287268074217, 2273.5680220758168, 2294.4837411582425, 2299.8275129365366, 2305.4236440889777, 16518.84368094234, 17527.73708394375, 17926.193321300572, 17978.91010544952, 17997.920124792556, 2137.8075973162336, 2162.7296587926508, 2165.4153378587694, 2179.2917301876955, 2180.2805074325593, 155404.0, 155852.0, 156642.0, 157926.0, 158967.2051639746, 10404.791904161875, 12210.267383956814, 12372.505099795908, 12413.627591172279, 12478.75042499145, 16172.676546469005, 16257.349706011628, 16302.429414970418, 16340.83659163417, 16341.303684892098, 8631.19085658105, 8871.467711937305, 8966.462012279284, 9077.954610227038, 9124.133207345292, 8490.745277641681, 8527.53098579584, 8528.445651032787, 8632.309415246891, 8717.825643487222, 7618.771436856903, 7696.230376962259, 7721.729739459092, 7755.92244077552, 7770.0, 2205.911763529473, 2214.169686367612, 2219.533897881463, 2220.322801545512, 2234.4860682043222, 984.842425211977, 990.6037584966072, 999.1806718490913, 1023.2859959567694, 1045.0855501436242, 20954.37136885896, 21445.249416270668, 21458.24896128628, 21593.35219943404, 21599.460013499473, 942.731321573362, 1022.1771473963382, 1042.0777611813598, 1042.801867645145, 1059.3590877519143, 9893.60425582983, 9972.252081093948, 10518.316309439862, 10828.72928176786, 10996.23026388145, 546032.0, 555113.0, 556072.0, 558663.0, 559404.0, 97163.0, 122151.0, 126682.0, 130073.0, 131615.0, 1084.6203828659907, 1181.1436708386352, 1215.343714394235, 1241.2862604002555, 1246.246021157205, 17660.293588256325, 18382.448526544224, 18394.908025459787, 18422.447326580223, 18798.812011880254, 49518.75240623774, 53645.92708145893, 54687.45312546824, 54765.726171368886, 55819.0, 75595.62202188953, 75643.24356756582, 76656.61671691606, 76949.0, 77386.61306693431, 8604.78488037804, 8633.69782057625, 8726.859467057573, 9032.32257580671, 9049.909500904907, 12888.162269452456, 13912.860871391156, 14046.087004345094, 14116.294185290899, 14274.714505710035, 11628.0, 11739.471723772622, 11862.22895511789, 11862.940685296518, 11984.322194892897, 11083.390413527333, 11086.279391839505, 11111.499982500969, 11387.658370248906, 11447.542098316308, 11270.380129092651, 11292.983631473207, 11727.824082638597, 11906.821397678868, 12206.0, 7353.485256032026, 7455.552666840007, 8743.606537705691, 8847.24798392144, 8884.73345799627, 11650.30098194111, 11826.704332391759, 12075.335856527623, 12093.334866582423, 12167.391630418266], "value": 10338.435829708907, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1354.532686223253, 1427.8215223097113, 1442.3365251984087, 1449.0508716790503, 1449.6086056764673, 11137.665870023902, 11276.830847537294, 11613.361265130414, 11614.767704645901, 11633.476493557779, 11323.886761132386, 11417.25787823793, 11495.0, 11510.597129100483, 11513.963743263104, 2125.7236559247317, 2595.195489398283, 2598.5062838060803, 2600.765931066204, 2603.271084096456, 2164.39396968849, 2505.073122944513, 2554.0932968796087, 2561.180422264876, 2561.8462892226435, 2207.3157321230387, 2448.9142879999204, 2451.852888826676, 2493.164789795421, 2502.4119331957068, 2443.2303824295377, 2483.192962287255, 2507.749225077496, 2511.949761004788, 2514.8365356251784, 2491.8504889706674, 2496.988719568578, 2497.9250622481354, 2503.474270403199, 2611.6474275972882, 11395.0, 11478.94260528692, 11487.885121148765, 11502.654920352403, 11530.250533715365, 6517.543771935966, 6678.599284042924, 6741.292164322774, 6745.527813053088, 6777.356151165681, 3978.80105994702, 4364.934525982113, 4415.558444155591, 4424.469063712375, 4444.20004399208, 1234.8950339221176, 1252.6930901929086, 1272.9745405091846, 1289.4648720780865, 1290.3354772292278, 12231.571894983634, 15652.217389130612, 15695.0, 15712.842871571253, 15840.366385344572, 5390.568754499631, 5462.6995515247045, 5596.160575913564, 5606.719664016783, 5624.3250809903075, 11220.326780393203, 11289.0, 11290.7177320566, 11308.151888608387, 11375.0, 6379.489640828722, 6410.61536307823, 6463.547551671432, 6468.3855033771715, 6478.0, 6001.339852616209, 6079.848003799941, 6114.144019837137, 6193.535484838655, 6253.6560489172625, 4270.615644592001, 4398.714083584557, 4414.9845535526365, 4451.755153466525, 4464.950736576902, 1290.6450726050412, 1305.4125643460482, 1315.4343632238229, 1321.7819059855208, 1329.454923481368, 737.5390381011867, 769.6151924037981, 801.8235988082613, 806.4838503357855, 840.2647683277132, 5634.492895639412, 6536.411722944958, 6897.310268973062, 6899.0341352210735, 6916.861662766716, 889.3507739350181, 905.4793493741125, 913.5386629751932, 923.0953665407937, 939.7274790310734, 3703.9073473324893, 3923.6076392361106, 4238.385434112037, 4288.378185163134, 4303.763293018851, 14092.718145637029, 14377.424903003972, 14544.345504452325, 14596.927015364856, 14631.634209144644, 13693.452261909411, 14355.712885742436, 14449.638759031108, 14546.781798273034, 14587.562373128823, 8038.075621303508, 8150.184981501802, 8324.750257492286, 8370.3303735701, 8407.2433480987, 6886.896696549656, 7113.395361394132, 7195.352418282379, 7206.207317195209, 7227.421806255382, 12996.610101696964, 13085.672858178621, 13142.094396312672, 13178.802317965046, 13266.933665331611, 13252.138610990254, 13306.933465332611, 13312.0, 13323.733525329246, 13674.606943784996, 8985.101489850962, 9286.117818807203, 9288.489133097744, 9292.442453452815, 9295.581698823704, 10380.532876020447, 10386.272960892666, 10409.32339397967, 10477.16182705397, 10512.632057877938, 8929.10708929102, 8945.73162805117, 8954.0, 9021.819563608822, 9055.501947392957, 8688.478691278544, 8730.69442569507, 8762.693305734269, 8771.692990745294, 8951.194392504445, 7963.362930965393, 8136.796580085545, 8142.18578142181, 8158.796030099057, 8159.592020399075, 5946.226990491208, 5971.492423144082, 6031.547633927561, 6140.478059365004, 6210.627362358273, 6026.698665066641, 6076.939230607637, 6172.938270617416, 6175.5677102602385, 6343.714532846126], "value": 5337.1025736236115, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.98997525061867, 403.7678334957399, 404.2561686496845, 404.4236962328681, 413.6256687697631, 6012.458878700916, 6193.165645458493, 6246.81310550995, 6264.84337891553, 6266.84332891678, 4575.8398456053965, 4974.626902982272, 5555.527795128045, 5879.52963762899, 6075.630504103403, 4130.958690413087, 4314.417553630251, 4625.838095666652, 4632.907341853161, 4650.534946505356, 3851.36452485341, 4642.675012749109, 4651.534846515338, 4666.300054991744, 4672.4393072831135, 3957.891790298721, 4452.933206001912, 4569.200389931765, 4598.793054312564, 4620.838270660528, 3752.6434988676033, 4323.286657701476, 4461.933071003938, 4568.720758187713, 4647.60495357895, 4074.2462644410584, 4082.1835632873544, 4110.321796903538, 4112.91774164518, 4113.765870238946, 4863.708177509326, 4895.828645997409, 5598.216249725042, 5809.402414336049, 6006.159137720722, 5235.476452354772, 5268.683878967236, 5272.2355258487305, 5286.60350473716, 5290.68255904647, 5437.592180586433, 5612.887742245173, 5929.0, 6153.446189842891, 6481.967590162066, 4278.2085314216965, 4291.849785257532, 4293.957060429387, 4307.440032795716, 4341.2836881914445, 5947.405259474018, 6693.6318502482345, 6828.951314605599, 6895.275996020446, 6928.891377379595, 3595.280943811248, 4002.6197511236387, 4068.6948478864206, 4363.887208761746, 4401.625861801719, 3791.0, 3904.5314562252142, 4438.533953934855, 4455.6658250631335, 4488.775561221926, 4834.540718631724, 5078.993797449986, 5208.166693329125, 5283.682979021271, 5297.576193904479, 6530.477561795045, 6541.738330466728, 6542.345765423514, 6558.0, 6562.475001999828, 1268.2644066441626, 1357.4773712120905, 1451.5209980706552, 1514.9318280677396, 1534.4936171063746, 844.953527555978, 951.2707383415196, 997.3816233935007, 999.955046975912, 1016.0855230292796, 6305.96847015762, 6326.398992095744, 6342.936570634327, 6350.6189628621505, 6382.978723404233, 6704.7988560343265, 6827.21487028998, 6836.863262734718, 6878.65606719662, 6889.931100689029, 5011.899762004739, 6187.504999600022, 6190.071489276645, 6241.906371404432, 6282.089097080898, 2902.883884644633, 2998.595189649413, 3384.0541688529192, 3513.94729079064, 3544.8759293424605, 6483.2544257411, 6747.359000894907, 6918.069450268564, 6948.895766563505, 7057.435405167575, 6132.938670613326, 6978.0, 6989.860202795916, 7004.964975175193, 7007.4394048476, 6754.345185429601, 6828.658567071627, 7011.263817299212, 7034.859302813813, 7067.43460523157, 5725.541956643542, 6486.740530378732, 6797.830054248683, 6809.5233333666165, 6815.284395138439, 6366.4588509977175, 6674.199096108498, 6731.629760363227, 6734.6632668367365, 6743.898841517479, 5144.536991670768, 6449.097126402401, 6918.927566227243, 6967.442604591519, 7114.466415018791, 3622.6015138334237, 4207.242696314693, 4251.787410629394, 4347.630496393625, 4451.59935605797, 5600.27196464457, 6623.503237257129, 6811.523193376416, 6879.174499060145, 6900.2409734928115, 5988.341282458839, 6583.48579826633, 6609.239937407259, 6616.106825578485, 6630.778689139, 5285.440794965418, 5740.770369185186, 6494.415502604788, 6597.142371491867, 6662.800115996528, 6406.839829004312, 6485.481161507162, 6551.508636852351, 6580.934190658032, 6598.934010660024, 6251.749370226624, 6270.153529273673, 6518.348165183444, 6590.637514936724, 6627.64718524936, 6344.873102538016, 6372.36276372359, 6426.29310775805, 6430.164078669743, 6433.839154021187], "value": 4726.200629995804, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [812.1310198088048, 812.9878301514615, 830.5930094253814, 841.511923084611, 843.974680759577, 11910.702232444193, 12206.0, 14149.646258843533, 14594.197319147443, 16280.0, 12941.676458088552, 14645.487407940724, 17014.574635634115, 17121.0, 17251.396201132964, 1000.5197505197509, 1021.780317231795, 1021.954012069455, 1022.2333250062453, 1024.0, 1007.098646711193, 1009.6869970309227, 1009.7728011197495, 1010.2372708605029, 1015.9136473399769, 1051.5163025008505, 1053.2837670384135, 1055.0346433013822, 1058.3861360410942, 1061.4639606998455, 1046.4401545173334, 1055.5461151704765, 1055.5724931402794, 1062.617457715222, 1068.3963560588281, 782.6282515805004, 786.5555960882141, 788.6096382290802, 790.4387884601939, 792.9088154862206, 16369.42707005261, 16442.917785411115, 16614.75077873833, 16660.08369539675, 16745.91627041857, 15661.92169039159, 16726.916365418096, 16951.57621059471, 17012.40456584026, 17099.57251068721, 13891.652708682364, 14234.786478202835, 14242.0, 14384.568462946128, 14484.637884052878, 3368.2758206985372, 3638.0904773806546, 3658.432942893856, 3669.9633003669887, 3671.30245253401, 98289.50855245821, 110548.34177487264, 111274.44362778215, 112413.43793281063, 112448.4377578107, 13488.797668034988, 13749.0, 13756.587302380945, 13770.036097473081, 13803.654908627164, 24871.378215544755, 25371.11201107989, 25525.87237063803, 25919.611205831927, 25942.35144121412, 7634.122075961335, 7634.770956871192, 7732.497387669783, 7774.961125194451, 7785.610719464004, 3314.1880239341517, 3897.7661340319673, 3919.4904662393706, 3921.9607803922167, 3927.4108883667677, 4260.850870219528, 4267.914641707149, 4389.429374181336, 4397.362382454527, 4401.801918913657, 915.2312057871287, 933.5985526223789, 949.0888746803147, 953.1469334945185, 955.7467271173144, 626.9059641053879, 646.5054233511368, 647.9546431749732, 649.6264647827518, 680.7447207297264, 9203.35576509638, 11213.271137376045, 11760.353180574979, 11927.761444771057, 11986.280823150639, 638.1353266324156, 641.2145122225237, 641.4804008752925, 642.7910928948111, 675.625028109404, 4321.481422229353, 5422.6475279106735, 5554.888902221933, 5555.722213889289, 5579.581531385163, 86123.0, 97270.05459890905, 98315.16550962473, 98384.50807745915, 100369.49815250876, 90436.0, 92141.53929230446, 92760.53619731858, 92853.0, 94089.5295523518, 76662.61668691621, 76679.61660191775, 77119.0, 77128.84306735403, 77217.45565088667, 11140.832887506533, 11250.549978000789, 11258.605948791754, 11265.71835704114, 11421.200515964136, 39197.0, 39911.40132897951, 40010.599629012846, 40178.0, 40376.19247614973, 50768.0, 50768.74615627046, 51250.0, 51355.0, 51890.74054629854, 13593.32033398346, 13629.795553066513, 13976.930115349358, 14041.946853986195, 14415.495457658932, 13120.934395327962, 14924.029938053991, 15201.695966080837, 15661.060336379398, 15758.369665213264, 13946.79079813824, 14410.135391876522, 14415.189038137463, 14552.0, 14658.78011829802, 14198.219097949712, 14457.710845783236, 14475.927620362252, 14501.347439365043, 14620.342084606005, 13462.326883655975, 13967.0, 14108.647283817987, 14335.78496322577, 14366.856331436551, 10290.0, 10494.632687855888, 10497.527611257665, 10640.255182137485, 10738.463076846283, 9240.41971942109, 9770.75573110678, 11412.885871141183, 11854.229475084092, 11953.0], "value": 7977.115679672467, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1509.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 115.0, 114.0, 114.0, 115.0, 115.0, 115.0, 114.0, 116.0, 115.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 112.0, 110.0, 145.0, 111.0, 106.0, 112.0, 102.0, 162.0, 93.0], "value": 118.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 185.0, 217.0, 238.0, 198.0, 214.0, 243.0, 204.0, 284.0, 281.0], "value": 225.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 55.0, 58.0, 79.0, 59.0, 58.0, 62.0, 69.0, 91.0, 47.0], "value": 66.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 69.0, 83.0, 72.0, 69.0, 72.0, 88.0, 63.0, 65.0, 88.0], "value": 75.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 92.0, 88.0, 88.0, 88.0, 89.0, 90.0, 88.0, 88.0], "value": 89.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 96.0, 96.0, 97.0, 125.0, 150.0, 114.0, 97.0], "value": 106.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 88.0, 88.0, 92.0, 89.0, 90.0, 93.0, 91.0, 91.0], "value": 90.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 48.0, 47.0, 48.0, 48.0, 50.0, 52.0, 52.0, 66.0, 74.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 58.0, 69.0, 57.0, 68.0, 65.0, 58.0, 56.0, 55.0, 58.0], "value": 60.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 81.0, 102.0, 101.0, 104.0, 108.0, 85.0, 84.0, 93.0, 74.0], "value": 91.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 133.0, 157.0, 160.0, 148.0, 138.0, 142.0, 140.0, 185.0, 139.0], "value": 147.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 182.0, 188.0, 183.0, 182.0, 184.0, 185.0, 186.0, 181.0, 184.0], "value": 185.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 100.0, 94.0, 100.0, 103.0, 78.0, 70.0, 68.0, 69.0, 70.0], "value": 85.2, "unit": "score"}]}]} 06:39:23 INFO - Return code: 0 06:39:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:39:23 INFO - # TBPL SUCCESS # 06:39:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:39:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:39:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:39:23 INFO - 2017-05-15 06:39:23.281 screenresolution[1670:10933] starting screenresolution argv=screenresolution get 06:39:23 INFO - 2017-05-15 06:39:23.284 screenresolution[1670:10933] Display 0: 1600x1200x32@60 06:39:23 INFO - 2017-05-15 06:39:23.290 screenresolution[1671:10936] starting screenresolution argv=screenresolution list 06:39:23 INFO - Available Modes on Display 0 06:39:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:39:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:39:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:39:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:39:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:39:23 INFO - Intel Iris: 06:39:23 INFO - Chipset Model: Intel Iris 06:39:23 INFO - Type: GPU 06:39:23 INFO - Bus: Built-In 06:39:23 INFO - VRAM (Dynamic, Max): 1536 MB 06:39:23 INFO - Vendor: Intel (0x8086) 06:39:23 INFO - Device ID: 0x0a2e 06:39:23 INFO - Revision ID: 0x0009 06:39:23 INFO - Displays: 06:39:23 INFO - Display: 06:39:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:39:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:39:23 INFO - Display Serial Number: 1600x1200 60 06:39:23 INFO - Main Display: Yes 06:39:23 INFO - Mirror: Off 06:39:23 INFO - Online: Yes 06:39:23 INFO - Rotation: Supported 06:39:23 INFO - Return code: 0 06:39:23 INFO - Running post-action listener: _resource_record_post_action 06:39:23 INFO - [mozharness: 2017-05-15 13:39:23.533430Z] Finished run-tests step (success) 06:39:23 INFO - Running post-run listener: _resource_record_post_run 06:39:23 INFO - Total resource usage - Wall time: 1194s; CPU: 54.0%; Read bytes: 38276096; Write bytes: 531731456; Read time: 252; Write time: 1628 06:39:23 INFO - TinderboxPrint: CPU usage
54.0% 06:39:23 INFO - TinderboxPrint: I/O read bytes / time
38,276,096 / 252 06:39:23 INFO - TinderboxPrint: I/O write bytes / time
531,731,456 / 1,628 06:39:23 INFO - TinderboxPrint: CPU idle
2,199.1 (46.0%) 06:39:23 INFO - TinderboxPrint: CPU system
956.2 (20.0%) 06:39:23 INFO - TinderboxPrint: CPU user
1,622.3 (34.0%) 06:39:23 INFO - TinderboxPrint: Swap in / out
253,251,584 / 0 06:39:23 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 206332416; Write bytes: 208365568; Read time: 16049; Write time: 438 06:39:23 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 37321728; Write bytes: 313515008; Read time: 238; Write time: 1156 06:39:24 INFO - Running post-run listener: _upload_blobber_files 06:39:24 INFO - Blob upload gear active. 06:39:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:39:24 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 06:39:24 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'] 06:39:24 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 06:39:24 INFO - (blobuploader) - INFO - Open directory for files ... 06:39:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:39:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:39:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:39:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:39:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:39:25 INFO - (blobuploader) - INFO - Done attempting. 06:39:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:39:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:39:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:39:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:39:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:39:26 INFO - (blobuploader) - INFO - Done attempting. 06:39:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:39:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:39:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:39:27 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:39:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:39:27 INFO - (blobuploader) - INFO - Done attempting. 06:39:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:39:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:39:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:39:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:39:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:39:29 INFO - (blobuploader) - INFO - Done attempting. 06:39:29 INFO - (blobuploader) - INFO - Iteration through files over. 06:39:29 INFO - Return code: 0 06:39:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:39:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:39:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb5c1805fc96b0120b9a033d78a106c3679be26c222c97050581fefa64cc5b2d3be40f4ec3bcdd92f622810f01b3e30f1bfd0f9975b45bbbf5a4cb10e139dc5f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f6959c7fc5897b27df5728e1f0343dbe5a278d90e27ebe5dfe38c789ba26020c728381249db0a1ec2d5b2c8b4e8f60d414388d3a9937ff5c3601fd0629d9d77", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/16b256741ea45dc91c77185d9b61b9196928a6d46ad02ee33326bce49405dfd70f120d6731b9f18fdd36d633a37b5f1791eb7bbc8718d8d2ca05ec5a1aa0cc76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6090d547406654957ba4a5d6a17fda34b3d89a79f441ce7dc5bc6ead37f60a8f0903b4ef3623646b9be9eda91781b3670d71c925bd01ad6f687156362ad2b5b4"} 06:39:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:39:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:39:29 INFO - Contents: 06:39:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb5c1805fc96b0120b9a033d78a106c3679be26c222c97050581fefa64cc5b2d3be40f4ec3bcdd92f622810f01b3e30f1bfd0f9975b45bbbf5a4cb10e139dc5f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f6959c7fc5897b27df5728e1f0343dbe5a278d90e27ebe5dfe38c789ba26020c728381249db0a1ec2d5b2c8b4e8f60d414388d3a9937ff5c3601fd0629d9d77", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/16b256741ea45dc91c77185d9b61b9196928a6d46ad02ee33326bce49405dfd70f120d6731b9f18fdd36d633a37b5f1791eb7bbc8718d8d2ca05ec5a1aa0cc76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6090d547406654957ba4a5d6a17fda34b3d89a79f441ce7dc5bc6ead37f60a8f0903b4ef3623646b9be9eda91781b3670d71c925bd01ad6f687156362ad2b5b4"} 06:39:29 INFO - Running post-run listener: copy_logs_to_upload_dir 06:39:29 INFO - Copying logs to upload dir... 06:39:29 INFO - mkdir: /builds/slave/test/build/upload/logs 06:39:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.502522 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-15 06:39:29.468555) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-15 06:39:29.472239) ========= 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.NjUOMBbJLs/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.qsheNtZmGY/Listeners TMPDIR=/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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/cb5c1805fc96b0120b9a033d78a106c3679be26c222c97050581fefa64cc5b2d3be40f4ec3bcdd92f622810f01b3e30f1bfd0f9975b45bbbf5a4cb10e139dc5f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f6959c7fc5897b27df5728e1f0343dbe5a278d90e27ebe5dfe38c789ba26020c728381249db0a1ec2d5b2c8b4e8f60d414388d3a9937ff5c3601fd0629d9d77", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/16b256741ea45dc91c77185d9b61b9196928a6d46ad02ee33326bce49405dfd70f120d6731b9f18fdd36d633a37b5f1791eb7bbc8718d8d2ca05ec5a1aa0cc76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6090d547406654957ba4a5d6a17fda34b3d89a79f441ce7dc5bc6ead37f60a8f0903b4ef3623646b9be9eda91781b3670d71c925bd01ad6f687156362ad2b5b4"} build_url:https://queue.taskcluster.net/v1/task/XrjAjgG6Q-qwE7f4gyJbWw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011130 build_url: 'https://queue.taskcluster.net/v1/task/XrjAjgG6Q-qwE7f4gyJbWw/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/cb5c1805fc96b0120b9a033d78a106c3679be26c222c97050581fefa64cc5b2d3be40f4ec3bcdd92f622810f01b3e30f1bfd0f9975b45bbbf5a4cb10e139dc5f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f6959c7fc5897b27df5728e1f0343dbe5a278d90e27ebe5dfe38c789ba26020c728381249db0a1ec2d5b2c8b4e8f60d414388d3a9937ff5c3601fd0629d9d77", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/16b256741ea45dc91c77185d9b61b9196928a6d46ad02ee33326bce49405dfd70f120d6731b9f18fdd36d633a37b5f1791eb7bbc8718d8d2ca05ec5a1aa0cc76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6090d547406654957ba4a5d6a17fda34b3d89a79f441ce7dc5bc6ead37f60a8f0903b4ef3623646b9be9eda91781b3670d71c925bd01ad6f687156362ad2b5b4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-15 06:39:29.504989) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 06:39:29.505275) ========= 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.NjUOMBbJLs/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.qsheNtZmGY/Listeners TMPDIR=/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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.005035 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 06:39:29.553609) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-15 06:39:29.553944) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-15 06:39:29.554264) ========= ========= Total master_lag: 0.18 =========