builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0029 starttime: 1498072071.94 results: success (0) buildid: 20170621111549 builduid: 2ad414d771ef407f8852b9add51654b4 revision: 4f227bec7585a7e4487d7da44c5cc7edd2ad5654 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:51.941343) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:51.941764) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:51.942036) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:51.965072) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:51.965393) ========= 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.O0oRF6k9Gj/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.CjaUPaQNeT/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-21 12:07:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.01M=0.001s 2017-06-21 12:07:52 (8.01 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.132631 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:52.120877) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:52.121396) ========= 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.O0oRF6k9Gj/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.CjaUPaQNeT/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.022000 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:52.170366) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-21 12:07:52.170701) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev 4f227bec7585a7e4487d7da44c5cc7edd2ad5654 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev 4f227bec7585a7e4487d7da44c5cc7edd2ad5654 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.O0oRF6k9Gj/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.CjaUPaQNeT/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-21 12:07:52,239 truncating revision to first 12 chars 2017-06-21 12:07:52,239 Setting DEBUG logging. 2017-06-21 12:07:52,240 attempt 1/10 2017-06-21 12:07:52,240 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/4f227bec7585?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-21 12:07:53,745 unpacking tar archive at: pine-4f227bec7585/testing/mozharness/ program finished with exit code 0 elapsedTime=1.749937 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-21 12:07:53.942133) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:53.942485) ========= script_repo_revision: 4f227bec7585a7e4487d7da44c5cc7edd2ad5654 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:53.942974) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:53.943235) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:53.960592) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-06-21 12:07:53.960875) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 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', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.O0oRF6k9Gj/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.CjaUPaQNeT/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:07:54 INFO - MultiFileLogger online at 20170621 12:07:54 in /builds/slave/test 12:07:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 12:07:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:07:54 INFO - {'append_to_log': False, 12:07:54 INFO - 'base_work_dir': '/builds/slave/test', 12:07:54 INFO - 'blob_upload_branch': 'Pine', 12:07:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:07:54 INFO - 'branch': 'Pine', 12:07:54 INFO - 'buildbot_json_path': 'buildprops.json', 12:07:54 INFO - 'config_files': ('talos/mac_config.py',), 12:07:54 INFO - 'default_actions': ('clobber', 12:07:54 INFO - 'read-buildbot-config', 12:07:54 INFO - 'download-and-extract', 12:07:54 INFO - 'populate-webroot', 12:07:54 INFO - 'create-virtualenv', 12:07:54 INFO - 'install', 12:07:54 INFO - 'setup-mitmproxy', 12:07:54 INFO - 'run-tests'), 12:07:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:07:54 INFO - 'download_minidump_stackwalk': True, 12:07:54 INFO - 'download_symbols': 'ondemand', 12:07:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:07:54 INFO - 'tooltool.py': '/tools/tooltool.py', 12:07:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:07:54 INFO - '/tools/misc-python/virtualenv.py')}, 12:07:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:07:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:07:54 INFO - 'gecko_profile': False, 12:07:54 INFO - 'gecko_profile_interval': 0, 12:07:54 INFO - 'installer_path': 'installer.exe', 12:07:54 INFO - 'log_level': 'info', 12:07:54 INFO - 'log_name': 'talos', 12:07:54 INFO - 'log_to_console': True, 12:07:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:07:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:07:54 INFO - 'opt_config_files': (), 12:07:54 INFO - 'pip_index': False, 12:07:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:07:54 INFO - 'cmd': ('bash', 12:07:54 INFO - '-c', 12:07:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:07:54 INFO - 'enabled': True, 12:07:54 INFO - 'halt_on_failure': False, 12:07:54 INFO - 'name': 'check_screen_resolution'},), 12:07:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:07:54 INFO - 'cmd': ('bash', 12:07:54 INFO - '-c', 12:07:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:07:54 INFO - 'enabled': True, 12:07:54 INFO - 'halt_on_failure': False, 12:07:54 INFO - 'name': 'check_screen_resolution'},), 12:07:54 INFO - 'run_cmd_checks_enabled': True, 12:07:54 INFO - 'suite': 'dromaeojs-e10s', 12:07:54 INFO - 'system_bits': '32', 12:07:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:07:54 INFO - 'title': 't-yosemite-r7-0029', 12:07:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:07:54 INFO - 'use_talos_json': True, 12:07:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:07:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:07:54 INFO - 'work_dir': 'build'} 12:07:54 INFO - [mozharness: 2017-06-21 19:07:54.161665Z] Running clobber step. 12:07:54 INFO - Running pre-action listener: _resource_record_pre_action 12:07:54 INFO - Running main action method: clobber 12:07:54 INFO - rmtree: /builds/slave/test/build 12:07:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:07:56 INFO - Running post-action listener: _resource_record_post_action 12:07:56 INFO - [mozharness: 2017-06-21 19:07:56.469421Z] Finished clobber step (success) 12:07:56 INFO - [mozharness: 2017-06-21 19:07:56.469555Z] Running read-buildbot-config step. 12:07:56 INFO - Running pre-action listener: _resource_record_pre_action 12:07:56 INFO - Running main action method: read_buildbot_config 12:07:56 INFO - Using buildbot properties: 12:07:56 INFO - { 12:07:56 INFO - "project": "", 12:07:56 INFO - "product": "firefox", 12:07:56 INFO - "script_repo_revision": "default", 12:07:56 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 12:07:56 INFO - "repository": "", 12:07:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 12:07:56 INFO - "buildid": "20170621111549", 12:07:56 INFO - "stage_platform": "macosx64", 12:07:56 INFO - "basedir": "/builds/slave/test", 12:07:56 INFO - "buildnumber": 1, 12:07:56 INFO - "platform": "yosemite_r7", 12:07:56 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 12:07:56 INFO - "slavebuilddir": "test", 12:07:56 INFO - "branch": "pine", 12:07:56 INFO - "revision": "4f227bec7585a7e4487d7da44c5cc7edd2ad5654", 12:07:56 INFO - "slavename": "t-yosemite-r7-0029", 12:07:56 INFO - "builduid": "2ad414d771ef407f8852b9add51654b4", 12:07:56 INFO - "repo_path": "projects/pine" 12:07:56 INFO - } 12:07:56 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.dmg. 12:07:56 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.test_packages.json. 12:07:56 INFO - Running post-action listener: _resource_record_post_action 12:07:56 INFO - [mozharness: 2017-06-21 19:07:56.472810Z] Finished read-buildbot-config step (success) 12:07:56 INFO - [mozharness: 2017-06-21 19:07:56.472945Z] Running download-and-extract step. 12:07:56 INFO - Running pre-action listener: _resource_record_pre_action 12:07:56 INFO - Running main action method: download_and_extract 12:07:56 INFO - mkdir: /builds/slave/test/build/tests 12:07:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:56 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.test_packages.json 12:07:56 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 12:07:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json'}, attempt #1 12:07:56 INFO - Downloaded 1328 bytes. 12:07:56 INFO - Reading from file /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 12:07:56 INFO - Using the following test package requirements: 12:07:56 INFO - {u'awsy': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:07:56 INFO - u'firefox-56.0a1.en-US.mac.awsy.tests.zip'], 12:07:56 INFO - u'common': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 12:07:56 INFO - u'cppunittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:07:56 INFO - u'firefox-56.0a1.en-US.mac.cppunittest.tests.zip'], 12:07:56 INFO - u'gtest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:07:56 INFO - u'firefox-56.0a1.en-US.mac.gtest.tests.zip'], 12:07:56 INFO - u'jittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:07:56 INFO - u'jsshell-mac.zip'], 12:07:56 INFO - u'mochitest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:07:56 INFO - u'firefox-56.0a1.en-US.mac.mochitest.tests.zip'], 12:07:56 INFO - u'mozbase': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 12:07:56 INFO - u'reftest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:07:56 INFO - u'firefox-56.0a1.en-US.mac.reftest.tests.zip'], 12:07:56 INFO - u'talos': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:07:56 INFO - u'firefox-56.0a1.en-US.mac.talos.tests.zip'], 12:07:56 INFO - u'web-platform': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:07:56 INFO - u'firefox-56.0a1.en-US.mac.web-platform.tests.tar.gz'], 12:07:56 INFO - u'xpcshell': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:07:56 INFO - u'firefox-56.0a1.en-US.mac.xpcshell.tests.zip']} 12:07:56 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:07:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.common.tests.zip 12:07:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:07:56 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.common.tests.zip into memory 12:07:58 INFO - Content-Length response header: 59596040 12:07:58 INFO - Bytes received: 59596040 12:08:15 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip', u'firefox-56.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:08:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.common.tests.zip 12:08:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:08:15 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.common.tests.zip into memory 12:08:17 INFO - Content-Length response header: 59596040 12:08:17 INFO - Bytes received: 59596040 12:08:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.talos.tests.zip 12:08:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:08:34 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.talos.tests.zip into memory 12:08:35 INFO - Content-Length response header: 14457085 12:08:35 INFO - Bytes received: 14457085 12:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:35 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.dmg 12:08:35 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg 12:08:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg'}, attempt #1 12:08:37 INFO - Downloaded 69703961 bytes. 12:08:37 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.dmg 12:08:37 INFO - mkdir: /builds/slave/test/properties 12:08:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:08:37 INFO - Writing to file /builds/slave/test/properties/build_url 12:08:37 INFO - Contents: 12:08:37 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.dmg 12:08:37 INFO - Running post-action listener: _resource_record_post_action 12:08:37 INFO - Running post-action listener: set_extra_try_arguments 12:08:37 INFO - [mozharness: 2017-06-21 19:08:37.493316Z] Finished download-and-extract step (success) 12:08:37 INFO - [mozharness: 2017-06-21 19:08:37.493435Z] Running populate-webroot step. 12:08:37 INFO - Running pre-action listener: _resource_record_pre_action 12:08:37 INFO - Running main action method: populate_webroot 12:08:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:08:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:08:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:08:37 INFO - '--tppagecycles', 12:08:37 INFO - '7'], 12:08:37 INFO - 'tests': ['tsvgm']}}, 12:08:37 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:08:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:08:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:08:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:08:37 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:08:37 INFO - 'talos_options': ['--disable-e10s'], 12:08:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:08:37 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:08:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:08:37 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:08:37 INFO - 'talos_options': ['--disable-e10s'], 12:08:37 INFO - 'tests': ['damp', 'tps']}, 12:08:37 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:08:37 INFO - 'tests': ['damp', 'tps']}, 12:08:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:08:37 INFO - 'tests': ['dromaeo_dom']}, 12:08:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:08:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:08:37 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 12:08:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:08:37 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:08:37 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:08:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:08:37 INFO - 'tests': ['a11yr', 12:08:37 INFO - 'ts_paint', 12:08:37 INFO - 'tpaint', 12:08:37 INFO - 'sessionrestore', 12:08:37 INFO - 'sessionrestore_no_auto_restore', 12:08:37 INFO - 'tabpaint']}, 12:08:37 INFO - 'other-e10s': {'tests': ['a11yr', 12:08:37 INFO - 'ts_paint', 12:08:37 INFO - 'tpaint', 12:08:37 INFO - 'sessionrestore', 12:08:37 INFO - 'sessionrestore_no_auto_restore', 12:08:37 INFO - 'tabpaint']}, 12:08:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:08:37 INFO - 'ts_paint', 12:08:37 INFO - 'tpaint', 12:08:37 INFO - 'sessionrestore', 12:08:37 INFO - 'sessionrestore_no_auto_restore', 12:08:37 INFO - 'tabpaint']}, 12:08:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:08:37 INFO - 'ts_paint', 12:08:37 INFO - 'tpaint', 12:08:37 INFO - 'sessionrestore', 12:08:37 INFO - 'sessionrestore_no_auto_restore', 12:08:37 INFO - 'tabpaint']}, 12:08:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:08:37 INFO - 'tests': ['a11yr', 12:08:37 INFO - 'ts_paint', 12:08:37 INFO - 'tpaint', 12:08:37 INFO - 'sessionrestore', 12:08:37 INFO - 'sessionrestore_no_auto_restore', 12:08:37 INFO - 'tabpaint']}, 12:08:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:08:37 INFO - 'tests': ['a11yr', 12:08:37 INFO - 'ts_paint', 12:08:37 INFO - 'tpaint', 12:08:37 INFO - 'sessionrestore', 12:08:37 INFO - 'sessionrestore_no_auto_restore', 12:08:37 INFO - 'tabpaint']}, 12:08:37 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 12:08:37 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 12:08:37 INFO - 'bloom_basic_ref']}, 12:08:37 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:08:37 INFO - 'talos_options': ['--mitmproxy', 12:08:37 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:08:37 INFO - '--firstNonBlankPaint'], 12:08:37 INFO - 'tests': ['quantum_pageload_google', 12:08:37 INFO - 'quantum_pageload_youtube', 12:08:37 INFO - 'quantum_pageload_amazon', 12:08:37 INFO - 'quantum_pageload_facebook']}, 12:08:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:08:37 INFO - 'tests': ['tsvgx', 12:08:37 INFO - 'tsvgr_opacity', 12:08:37 INFO - 'tart', 12:08:37 INFO - 'tscrollx', 12:08:37 INFO - 'cart', 12:08:37 INFO - 'tsvg_static']}, 12:08:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:08:37 INFO - 'tsvgr_opacity', 12:08:37 INFO - 'tart', 12:08:37 INFO - 'tscrollx', 12:08:37 INFO - 'cart', 12:08:37 INFO - 'tsvg_static']}, 12:08:37 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:08:37 INFO - 'talos_options': ['--disable-e10s'], 12:08:37 INFO - 'tests': ['tp5o']}, 12:08:37 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:08:37 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:08:37 INFO - 'talos_options': ['--disable-e10s', 12:08:37 INFO - '--xperf_path', 12:08:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:08:37 INFO - 'tests': ['tp5n']}, 12:08:37 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:08:37 INFO - 'talos_options': ['--xperf_path', 12:08:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:08:37 INFO - 'tests': ['tp5n']}}, 12:08:37 INFO - 'talos.zip': {'path': '', 12:08:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:08:37 INFO - Running post-action listener: _resource_record_post_action 12:08:37 INFO - [mozharness: 2017-06-21 19:08:37.514203Z] Finished populate-webroot step (success) 12:08:37 INFO - [mozharness: 2017-06-21 19:08:37.514341Z] Running create-virtualenv step. 12:08:37 INFO - Running pre-action listener: _resource_record_pre_action 12:08:37 INFO - Running main action method: create_virtualenv 12:08:37 INFO - Creating virtualenv /builds/slave/test/build/venv 12:08:37 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 12:08:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:08:37 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:08:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:08:37 INFO - Using real prefix '/tools/python27' 12:08:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:08:38 INFO - Installing distribute.............................................................................................................................................................................................done. 12:08:41 INFO - Installing pip.................done. 12:08:41 INFO - Return code: 0 12:08:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:08:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:08:41 INFO - Reading from file tmpfile_stdout 12:08:41 INFO - Output received: 12:08:41 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) 12:08:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:08:41 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')]} 12:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:41 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')]} 12:08:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:41 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 0x10fc11c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc15768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd88a4a5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f967d30>, '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 0x10f931cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f958be8>, '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.O0oRF6k9Gj/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.CjaUPaQNeT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:08:41 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 12:08:41 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 12:08:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O0oRF6k9Gj/Render', 12:08:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:41 INFO - 'HOME': '/Users/cltbld', 12:08:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:41 INFO - 'LOGNAME': 'cltbld', 12:08:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:41 INFO - 'MOZ_NO_REMOTE': '1', 12:08:41 INFO - 'NO_EM_RESTART': '1', 12:08:41 INFO - 'PAGER': '/bin/cat', 12:08:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:41 INFO - 'PWD': '/builds/slave/test', 12:08:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:41 INFO - 'SHELL': '/bin/bash', 12:08:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CjaUPaQNeT/Listeners', 12:08:41 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:08:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:41 INFO - 'USER': 'cltbld', 12:08:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:41 INFO - 'XPC_FLAGS': '0x0', 12:08:41 INFO - 'XPC_SERVICE_NAME': '0', 12:08:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:41 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 12:08:41 INFO - Cleaning up... 12:08:41 INFO - Return code: 0 12:08:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:08:41 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')]} 12:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:41 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')]} 12:08:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:41 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 0x10fc11c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc15768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd88a4a5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f967d30>, '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 0x10f931cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f958be8>, '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.O0oRF6k9Gj/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.CjaUPaQNeT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:08:41 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 12:08:41 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 12:08:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O0oRF6k9Gj/Render', 12:08:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:41 INFO - 'HOME': '/Users/cltbld', 12:08:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:41 INFO - 'LOGNAME': 'cltbld', 12:08:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:41 INFO - 'MOZ_NO_REMOTE': '1', 12:08:41 INFO - 'NO_EM_RESTART': '1', 12:08:41 INFO - 'PAGER': '/bin/cat', 12:08:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:41 INFO - 'PWD': '/builds/slave/test', 12:08:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:41 INFO - 'SHELL': '/bin/bash', 12:08:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CjaUPaQNeT/Listeners', 12:08:41 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:08:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:41 INFO - 'USER': 'cltbld', 12:08:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:41 INFO - 'XPC_FLAGS': '0x0', 12:08:41 INFO - 'XPC_SERVICE_NAME': '0', 12:08:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:41 INFO - Downloading/unpacking psutil>=3.1.1 12:08:41 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 12:08:41 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 12:08:41 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 12:08:41 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 12:08:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:08:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:08:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:08:43 INFO - Installing collected packages: psutil 12:08:43 INFO - Running setup.py install for psutil 12:08:44 INFO - building 'psutil._psutil_osx' extension 12:08:44 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 12:08:44 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 12:08:44 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 12:08:44 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 12:08:44 INFO - building 'psutil._psutil_posix' extension 12:08:44 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 12:08:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:08:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:08:44 INFO - ^ 12:08:44 INFO - 1 warning generated. 12:08:44 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 12:08:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:08:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:08:44 INFO - Successfully installed psutil 12:08:44 INFO - Cleaning up... 12:08:44 INFO - Return code: 0 12:08:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:08:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:44 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 0x10fc11c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc15768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd88a4a5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f967d30>, '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 0x10f931cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f958be8>, '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.O0oRF6k9Gj/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.CjaUPaQNeT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:08:44 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 12:08:44 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 12:08:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O0oRF6k9Gj/Render', 12:08:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:44 INFO - 'HOME': '/Users/cltbld', 12:08:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:44 INFO - 'LOGNAME': 'cltbld', 12:08:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:44 INFO - 'MOZ_NO_REMOTE': '1', 12:08:44 INFO - 'NO_EM_RESTART': '1', 12:08:44 INFO - 'PAGER': '/bin/cat', 12:08:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:44 INFO - 'PWD': '/builds/slave/test', 12:08:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:44 INFO - 'SHELL': '/bin/bash', 12:08:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CjaUPaQNeT/Listeners', 12:08:44 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:08:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:44 INFO - 'USER': 'cltbld', 12:08:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:44 INFO - 'XPC_FLAGS': '0x0', 12:08:44 INFO - 'XPC_SERVICE_NAME': '0', 12:08:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:08:45 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 12:08:45 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 12:08:45 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 12:08:45 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 12:08:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:08:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:08:46 INFO - Installing collected packages: mozsystemmonitor 12:08:46 INFO - Running setup.py install for mozsystemmonitor 12:08:46 INFO - Successfully installed mozsystemmonitor 12:08:46 INFO - Cleaning up... 12:08:46 INFO - Return code: 0 12:08:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:08:46 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')]} 12:08:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:46 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')]} 12:08:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:46 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 0x10fc11c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc15768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd88a4a5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f967d30>, '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 0x10f931cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f958be8>, '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.O0oRF6k9Gj/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.CjaUPaQNeT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:08:46 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 12:08:46 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 12:08:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O0oRF6k9Gj/Render', 12:08:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:46 INFO - 'HOME': '/Users/cltbld', 12:08:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:46 INFO - 'LOGNAME': 'cltbld', 12:08:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:46 INFO - 'MOZ_NO_REMOTE': '1', 12:08:46 INFO - 'NO_EM_RESTART': '1', 12:08:46 INFO - 'PAGER': '/bin/cat', 12:08:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:46 INFO - 'PWD': '/builds/slave/test', 12:08:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:46 INFO - 'SHELL': '/bin/bash', 12:08:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CjaUPaQNeT/Listeners', 12:08:46 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:08:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:46 INFO - 'USER': 'cltbld', 12:08:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:46 INFO - 'XPC_FLAGS': '0x0', 12:08:46 INFO - 'XPC_SERVICE_NAME': '0', 12:08:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:47 INFO - Downloading/unpacking jsonschema==2.5.1 12:08:47 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 12:08:47 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 12:08:47 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 12:08:47 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 12:08:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:08:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:08:48 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 12:08:48 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 12:08:48 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 12:08:48 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 12:08:48 INFO - Downloading functools32-3.2.3-2.tar.gz 12:08:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:08:48 INFO - warning: no files found matching '*.txt' 12:08:48 INFO - no previously-included directories found matching 'build' 12:08:48 INFO - no previously-included directories found matching 'dist' 12:08:48 INFO - no previously-included directories found matching '.git*' 12:08:48 INFO - Installing collected packages: jsonschema, functools32 12:08:48 INFO - Running setup.py install for functools32 12:08:48 INFO - warning: no files found matching '*.txt' 12:08:48 INFO - no previously-included directories found matching 'build' 12:08:48 INFO - no previously-included directories found matching 'dist' 12:08:48 INFO - no previously-included directories found matching '.git*' 12:08:48 INFO - Successfully installed jsonschema functools32 12:08:48 INFO - Cleaning up... 12:08:49 INFO - Return code: 0 12:08:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:08: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')]} 12:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08: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')]} 12:08:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:49 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 0x10fc11c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc15768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd88a4a5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f967d30>, '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 0x10f931cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f958be8>, '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.O0oRF6k9Gj/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.CjaUPaQNeT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:08:49 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 12:08:49 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 12:08:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O0oRF6k9Gj/Render', 12:08:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:49 INFO - 'HOME': '/Users/cltbld', 12:08:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:49 INFO - 'LOGNAME': 'cltbld', 12:08:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:49 INFO - 'MOZ_NO_REMOTE': '1', 12:08:49 INFO - 'NO_EM_RESTART': '1', 12:08:49 INFO - 'PAGER': '/bin/cat', 12:08:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:49 INFO - 'PWD': '/builds/slave/test', 12:08:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:49 INFO - 'SHELL': '/bin/bash', 12:08:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CjaUPaQNeT/Listeners', 12:08:49 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:08:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:49 INFO - 'USER': 'cltbld', 12:08:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:49 INFO - 'XPC_FLAGS': '0x0', 12:08:49 INFO - 'XPC_SERVICE_NAME': '0', 12:08:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:08:50 INFO - Cleaning up... 12:08:50 INFO - Return code: 0 12:08:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:08:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fc11c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc15768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd88a4a5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f967d30>, '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 0x10f931cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f958be8>, '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.O0oRF6k9Gj/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.CjaUPaQNeT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:08:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:08:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:08:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O0oRF6k9Gj/Render', 12:08:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:50 INFO - 'HOME': '/Users/cltbld', 12:08:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:50 INFO - 'LOGNAME': 'cltbld', 12:08:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:50 INFO - 'MOZ_NO_REMOTE': '1', 12:08:50 INFO - 'NO_EM_RESTART': '1', 12:08:50 INFO - 'PAGER': '/bin/cat', 12:08:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:50 INFO - 'PWD': '/builds/slave/test', 12:08:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:50 INFO - 'SHELL': '/bin/bash', 12:08:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CjaUPaQNeT/Listeners', 12:08:50 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:08:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:50 INFO - 'USER': 'cltbld', 12:08:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:50 INFO - 'XPC_FLAGS': '0x0', 12:08:50 INFO - 'XPC_SERVICE_NAME': '0', 12:08:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:51 INFO - Downloading/unpacking blobuploader==1.2.4 12:08:51 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 12:08:51 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 12:08:51 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 12:08:51 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 12:08:53 INFO - Downloading blobuploader-1.2.4.tar.gz 12:08:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:08:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:08:53 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 12:08:53 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 12:08:53 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 12:08:53 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 12:08:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:08:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:08:53 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 12:08:53 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 12:08:53 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 12:08:53 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 12:08:53 INFO - Downloading docopt-0.6.1.tar.gz 12:08:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:08:53 INFO - Installing collected packages: blobuploader, requests, docopt 12:08:53 INFO - Running setup.py install for blobuploader 12:08:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:08:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:08:54 INFO - Running setup.py install for requests 12:08:54 INFO - Running setup.py install for docopt 12:08:54 INFO - Successfully installed blobuploader requests docopt 12:08:54 INFO - Cleaning up... 12:08:54 INFO - Return code: 0 12:08:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:08:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:54 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 0x10fc11c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc15768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd88a4a5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f967d30>, '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 0x10f931cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f958be8>, '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.O0oRF6k9Gj/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.CjaUPaQNeT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:08:54 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 12:08:54 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 12:08:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O0oRF6k9Gj/Render', 12:08:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:54 INFO - 'HOME': '/Users/cltbld', 12:08:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:54 INFO - 'LOGNAME': 'cltbld', 12:08:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:54 INFO - 'MOZ_NO_REMOTE': '1', 12:08:54 INFO - 'NO_EM_RESTART': '1', 12:08:54 INFO - 'PAGER': '/bin/cat', 12:08:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:54 INFO - 'PWD': '/builds/slave/test', 12:08:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:54 INFO - 'SHELL': '/bin/bash', 12:08:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CjaUPaQNeT/Listeners', 12:08:54 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:08:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:54 INFO - 'USER': 'cltbld', 12:08:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:54 INFO - 'XPC_FLAGS': '0x0', 12:08:54 INFO - 'XPC_SERVICE_NAME': '0', 12:08:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:08:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-2iH7gZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:08:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-LGY4xH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:08:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-O0hYtO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:08:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-hS6Dee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:08:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-J_Igj_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:08:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-rRLFIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:08:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-pVh5XY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:08:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-m0mUH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:08:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-DBzT6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:08:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-8p3J5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:08:56 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Fq2sSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:08:56 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-2T6bqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:08:56 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-5AvLp5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:08:56 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-H8tUkw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:56 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-3x5k_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:08:56 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-ezRBW5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:08:56 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-ItJtWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:08:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:08:56 INFO - Running setup.py install for manifestparser 12:08:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:08:56 INFO - Running setup.py install for mozcrash 12:08:57 INFO - Running setup.py install for mozdebug 12:08:57 INFO - Running setup.py install for mozdevice 12:08:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:08:57 INFO - Running setup.py install for mozfile 12:08:57 INFO - Running setup.py install for mozhttpd 12:08:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:08:57 INFO - Running setup.py install for mozinfo 12:08:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:08:57 INFO - Running setup.py install for mozInstall 12:08:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:08:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:08:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:08:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:08:57 INFO - Running setup.py install for mozleak 12:08:58 INFO - Running setup.py install for mozlog 12:08:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:08:58 INFO - Running setup.py install for moznetwork 12:08:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:08:58 INFO - Running setup.py install for mozprocess 12:08:58 INFO - Running setup.py install for mozprofile 12:08:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:08:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:08:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:08:58 INFO - Running setup.py install for mozrunner 12:08:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:08:58 INFO - Running setup.py install for mozscreenshot 12:08:59 INFO - Running setup.py install for moztest 12:08:59 INFO - Running setup.py install for mozversion 12:08:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:08:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:08:59 INFO - Cleaning up... 12:08:59 INFO - Return code: 0 12:08:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:08:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:59 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 0x10fc11c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc15768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd88a4a5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f967d30>, '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 0x10f931cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f958be8>, '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.O0oRF6k9Gj/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.CjaUPaQNeT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:08:59 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 12:08:59 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 12:08:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O0oRF6k9Gj/Render', 12:08:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:59 INFO - 'HOME': '/Users/cltbld', 12:08:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:59 INFO - 'LOGNAME': 'cltbld', 12:08:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:59 INFO - 'MOZ_NO_REMOTE': '1', 12:08:59 INFO - 'NO_EM_RESTART': '1', 12:08:59 INFO - 'PAGER': '/bin/cat', 12:08:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:59 INFO - 'PWD': '/builds/slave/test', 12:08:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:59 INFO - 'SHELL': '/bin/bash', 12:08:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CjaUPaQNeT/Listeners', 12:08:59 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:08:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:59 INFO - 'USER': 'cltbld', 12:08:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:59 INFO - 'XPC_FLAGS': '0x0', 12:08:59 INFO - 'XPC_SERVICE_NAME': '0', 12:08:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:08:59 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-OvZ10f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:08:59 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)) 12:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:08:59 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-KRL2vi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:08:59 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)) 12:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:08:59 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-87TDVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:08:59 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)) 12:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:08:59 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-S07DX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:09:00 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-JAhJBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:09:00 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)) 12:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:09:00 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-cdGlJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:09:00 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)) 12:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:09:00 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-pFVaPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:09:00 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-OHjD0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:09:00 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-uWpYmR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:09:00 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)) 12:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:09:00 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-8W1OQo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:09:00 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)) 12:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:09:00 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-5Pt2WB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:09:00 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)) 12:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:09:00 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-YqKMS8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:09:00 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)) 12:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:09:00 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Vfu13f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:09:01 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)) 12:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:09:01 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-OzC08n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:09:01 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)) 12:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:01 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-uRFZci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:01 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)) 12:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:09:01 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-5FuJ3T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:09:01 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)) 12:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:09:01 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-8Sep2H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:09:01 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)) 12:09:01 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)) 12:09:01 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)) 12:09:01 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)) 12:09:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:09:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:09:01 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:09:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:02 INFO - Downloading blessings-1.6.tar.gz 12:09:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:09:02 INFO - Installing collected packages: blessings 12:09:02 INFO - Running setup.py install for blessings 12:09:03 INFO - Successfully installed blessings 12:09:03 INFO - Cleaning up... 12:09:03 INFO - Return code: 0 12:09:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:09:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:09:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:09:03 INFO - Reading from file tmpfile_stdout 12:09:03 INFO - Current package versions: 12:09:03 INFO - blessings == 1.6 12:09:03 INFO - blobuploader == 1.2.4 12:09:03 INFO - docopt == 0.6.1 12:09:03 INFO - functools32 == 3.2.3-2 12:09:03 INFO - jsonschema == 2.5.1 12:09:03 INFO - manifestparser == 1.1 12:09:03 INFO - mozInstall == 1.13 12:09:03 INFO - mozcrash == 1.0 12:09:03 INFO - mozdebug == 0.1 12:09:03 INFO - mozdevice == 0.51 12:09:03 INFO - mozfile == 1.2 12:09:03 INFO - mozhttpd == 0.7 12:09:03 INFO - mozinfo == 0.10 12:09:03 INFO - mozleak == 0.1 12:09:03 INFO - mozlog == 3.4 12:09:03 INFO - moznetwork == 0.27 12:09:03 INFO - mozprocess == 0.25 12:09:03 INFO - mozprofile == 0.28 12:09:03 INFO - mozrunner == 6.13 12:09:03 INFO - mozscreenshot == 0.1 12:09:03 INFO - mozsystemmonitor == 0.3 12:09:03 INFO - moztest == 0.8 12:09:03 INFO - mozversion == 1.4 12:09:03 INFO - psutil == 3.1.1 12:09:03 INFO - requests == 1.2.3 12:09:03 INFO - wsgiref == 0.1.2 12:09:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:03 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 0x10fc11c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc15768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd88a4a5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f967d30>, '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 0x10f931cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f958be8>, '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.O0oRF6k9Gj/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.CjaUPaQNeT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:09:03 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 12:09:03 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 12:09:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O0oRF6k9Gj/Render', 12:09:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:03 INFO - 'HOME': '/Users/cltbld', 12:09:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:03 INFO - 'LOGNAME': 'cltbld', 12:09:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:03 INFO - 'MOZ_NO_REMOTE': '1', 12:09:03 INFO - 'NO_EM_RESTART': '1', 12:09:03 INFO - 'PAGER': '/bin/cat', 12:09:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:03 INFO - 'PWD': '/builds/slave/test', 12:09:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:03 INFO - 'SHELL': '/bin/bash', 12:09:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CjaUPaQNeT/Listeners', 12:09:03 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:09:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:03 INFO - 'USER': 'cltbld', 12:09:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:03 INFO - 'XPC_FLAGS': '0x0', 12:09:03 INFO - 'XPC_SERVICE_NAME': '0', 12:09:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:03 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)) 12:09:03 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)) 12:09:03 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)) 12:09:03 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)) 12:09:03 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)) 12:09:03 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)) 12:09:03 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)) 12:09:03 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)) 12:09:03 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)) 12:09:03 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:09:03 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 12:09:03 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 12:09:03 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 12:09:03 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 12:09:05 INFO - Downloading marionette_driver-1.4.0.tar.gz 12:09:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 12:09:05 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)) 12:09:05 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)) 12:09:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:09:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:09:05 INFO - Installing collected packages: marionette-driver 12:09:05 INFO - Running setup.py install for marionette-driver 12:09:05 INFO - Successfully installed marionette-driver 12:09:05 INFO - Cleaning up... 12:09:05 INFO - Return code: 0 12:09:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:09:05 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')]} 12:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:05 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')]} 12:09:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:05 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 0x10fc11c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc15768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd88a4a5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f967d30>, '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 0x10f931cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f958be8>, '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.O0oRF6k9Gj/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.CjaUPaQNeT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:09:05 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 12:09:05 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 12:09:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O0oRF6k9Gj/Render', 12:09:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:05 INFO - 'HOME': '/Users/cltbld', 12:09:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:05 INFO - 'LOGNAME': 'cltbld', 12:09:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:05 INFO - 'MOZ_NO_REMOTE': '1', 12:09:05 INFO - 'NO_EM_RESTART': '1', 12:09:05 INFO - 'PAGER': '/bin/cat', 12:09:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:05 INFO - 'PWD': '/builds/slave/test', 12:09:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:05 INFO - 'SHELL': '/bin/bash', 12:09:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CjaUPaQNeT/Listeners', 12:09:05 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:09:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:05 INFO - 'USER': 'cltbld', 12:09:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:05 INFO - 'XPC_FLAGS': '0x0', 12:09:05 INFO - 'XPC_SERVICE_NAME': '0', 12:09:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:09:05 INFO - Cleaning up... 12:09:05 INFO - Return code: 0 12:09:05 INFO - Running post-action listener: _resource_record_post_action 12:09:05 INFO - Running post-action listener: _start_resource_monitoring 12:09:05 INFO - Starting resource monitoring. 12:09:05 INFO - [mozharness: 2017-06-21 19:09:05.704554Z] Finished create-virtualenv step (success) 12:09:05 INFO - [mozharness: 2017-06-21 19:09:05.705535Z] Running install step. 12:09:05 INFO - Running pre-action listener: _resource_record_pre_action 12:09:05 INFO - Running main action method: install 12:09:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:09:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:09:05 INFO - Reading from file tmpfile_stdout 12:09:05 INFO - Detecting whether we're running mozinstall >=1.0... 12:09:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:09:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:09:06 INFO - Reading from file tmpfile_stdout 12:09:06 INFO - Output received: 12:09:06 INFO - Usage: mozinstall [options] installer 12:09:06 INFO - Options: 12:09:06 INFO - -h, --help show this help message and exit 12:09:06 INFO - -d DEST, --destination=DEST 12:09:06 INFO - Directory to install application into. [default: 12:09:06 INFO - "/builds/slave/test"] 12:09:06 INFO - --app=APP Application being installed. [default: firefox] 12:09:06 INFO - mkdir: /builds/slave/test/build/application 12:09:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:09:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:09:28 INFO - Reading from file tmpfile_stdout 12:09:28 INFO - Output received: 12:09:28 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:09:28 INFO - Running post-action listener: _resource_record_post_action 12:09:28 INFO - [mozharness: 2017-06-21 19:09:28.487230Z] Finished install step (success) 12:09:28 INFO - [mozharness: 2017-06-21 19:09:28.487376Z] Running setup-mitmproxy step. 12:09:28 INFO - Running pre-action listener: _resource_record_pre_action 12:09:28 INFO - Running main action method: setup_mitmproxy 12:09:28 INFO - Skipping: mitmproxy is not required 12:09:28 INFO - Running post-action listener: _resource_record_post_action 12:09:28 INFO - [mozharness: 2017-06-21 19:09:28.488040Z] Finished setup-mitmproxy step (success) 12:09:28 INFO - [mozharness: 2017-06-21 19:09:28.488166Z] Running run-tests step. 12:09:28 INFO - Running pre-action listener: _resource_record_pre_action 12:09:28 INFO - Running main action method: run_tests 12:09:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:09:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:09:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:09:28 INFO - 2017-06-21 12:09:28.503 screenresolution[1919:12321] starting screenresolution argv=screenresolution get 12:09:28 INFO - 2017-06-21 12:09:28.505 screenresolution[1919:12321] Display 0: 1600x1200x32@60 12:09:28 INFO - 2017-06-21 12:09:28.511 screenresolution[1920:12323] starting screenresolution argv=screenresolution list 12:09:28 INFO - Available Modes on Display 0 12:09:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:09:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:09:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:09:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:09:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:09:28 INFO - Intel Iris: 12:09:28 INFO - Chipset Model: Intel Iris 12:09:28 INFO - Type: GPU 12:09:28 INFO - Bus: Built-In 12:09:28 INFO - VRAM (Dynamic, Max): 1536 MB 12:09:28 INFO - Vendor: Intel (0x8086) 12:09:28 INFO - Device ID: 0x0a2e 12:09:28 INFO - Revision ID: 0x0009 12:09:28 INFO - Displays: 12:09:28 INFO - Display: 12:09:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:09:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:09:28 INFO - Display Serial Number: 1600x1200 60 12:09:28 INFO - Main Display: Yes 12:09:28 INFO - Mirror: Off 12:09:28 INFO - Online: Yes 12:09:28 INFO - Rotation: Supported 12:09:28 INFO - Return code: 0 12:09:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:09:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:09:28 INFO - Python 2.7.3 12:09:28 INFO - Return code: 0 12:09:28 INFO - grabbing minidump binary from tooltool 12:09:28 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')]} 12:09:28 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 0x10f967d30>, '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 0x10f931cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f958be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:09:28 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 12:09:28 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 12:09:28 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 12:09:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:09:28 INFO - Return code: 0 12:09:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:09:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:09:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:09:28 INFO - ENV: RUST_BACKTRACE is now 1 12:09:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:09:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:09:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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 12:09:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0029 --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 12:09:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O0oRF6k9Gj/Render', 12:09:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:28 INFO - 'HOME': '/Users/cltbld', 12:09:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:28 INFO - 'LOGNAME': 'cltbld', 12:09:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:09:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:09:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:28 INFO - 'MOZ_NO_REMOTE': '1', 12:09:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:09:28 INFO - 'NO_EM_RESTART': '1', 12:09:28 INFO - 'PAGER': '/bin/cat', 12:09:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:28 INFO - 'PWD': '/builds/slave/test', 12:09:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:09:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:28 INFO - 'RUST_BACKTRACE': '1', 12:09:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:09:28 INFO - 'SHELL': '/bin/bash', 12:09:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CjaUPaQNeT/Listeners', 12:09:28 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:09:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:28 INFO - 'USER': 'cltbld', 12:09:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:28 INFO - 'XPC_FLAGS': '0x0', 12:09:28 INFO - 'XPC_SERVICE_NAME': '0', 12:09:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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 12:09:29 INFO - mozversion application_buildid: 20170620132059 12:09:29 INFO - mozversion application_changeset: 4f227bec7585a7e4487d7da44c5cc7edd2ad5654 12:09:29 INFO - mozversion application_display_name: Nightly 12:09:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:09:29 INFO - mozversion application_name: Firefox 12:09:29 INFO - mozversion application_remotingname: firefox 12:09:29 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 12:09:29 INFO - mozversion application_vendor: Mozilla 12:09:29 INFO - mozversion application_version: 56.0a1 12:09:29 INFO - mozversion platform_buildid: 20170620132059 12:09:29 INFO - mozversion platform_changeset: 4f227bec7585a7e4487d7da44c5cc7edd2ad5654 12:09:29 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 12:09:29 INFO - mozversion platform_version: 56.0a1 12:09:29 INFO - using testdate: 1498072169 12:09:29 INFO - actual date: 1498072169 12:09:29 INFO - starting webserver on 'localhost:49336' 12:09:29 INFO - SUITE-START | Running 2 tests 12:09:29 INFO - TEST-START | dromaeo_css 12:09:29 INFO - operating with platform_type : mac_ 12:09:29 INFO - Initialising browser for dromaeo_css test... 12:09:29 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49336/getInfo.html -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmp4VfdHN/profile 12:09:29 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49336/getInfo.html -foreground) 12:09:33 INFO - TEST-INFO | 1930: exit 0 12:09:33 INFO - Browser initialized. 12:09:33 INFO - Running cycle 1/1 for dromaeo_css test... 12:09:34 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmp4VfdHN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:09:36 INFO - PID 1936 | 2017-06-21 12:09:36.081 plugin-container[1937:12706] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x993f, name = 'com.apple.tsm.portname' 12:09:36 INFO - PID 1936 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:09:36 INFO - PID 1936 | 2017-06-21 12:09:36.082 plugin-container[1937:12706] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b1b, name = 'com.apple.CFPasteboardClient' 12:09:36 INFO - PID 1936 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:09:36 INFO - PID 1936 | 2017-06-21 12:09:36.082 plugin-container[1937:12706] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:09:36 INFO - PID 1936 | 2017-06-21 12:09:36.647 plugin-container[1938:12757] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 12:09:36 INFO - PID 1936 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:09:36 INFO - PID 1936 | 2017-06-21 12:09:36.648 plugin-container[1938:12757] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 12:09:36 INFO - PID 1936 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:09:36 INFO - PID 1936 | 2017-06-21 12:09:36.648 plugin-container[1938:12757] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:09:45 INFO - PID 1936 | RSS: Main: 249315328 12:09:45 INFO - PID 1936 | 12:12:52 INFO - PID 1936 | Cycle 1(1): loaded http://localhost:49336/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49336/tests/dromaeo/cssquery-ext.html) 12:12:54 INFO - PID 1936 | RSS: Main: 265302016 12:12:54 INFO - PID 1936 | 12:15:56 INFO - PID 1936 | Cycle 1(1): loaded http://localhost:49336/tests/dromaeo/cssquery-ext.html (next: http://localhost:49336/tests/dromaeo/cssquery-jquery.html) 12:15:56 INFO - PID 1936 | RSS: Main: 266829824 12:15:56 INFO - PID 1936 | 12:18:58 INFO - PID 1936 | Cycle 1(1): loaded http://localhost:49336/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49336/tests/dromaeo/cssquery-mootools.html) 12:18:59 INFO - PID 1936 | RSS: Main: 271204352 12:18:59 INFO - PID 1936 | 12:22:00 INFO - PID 1936 | Cycle 1(1): loaded http://localhost:49336/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49336/tests/dromaeo/cssquery-prototype.html) 12:22:01 INFO - PID 1936 | RSS: Main: 270802944 12:22:01 INFO - PID 1936 | 12:25:04 INFO - PID 1936 | Cycle 1(1): loaded http://localhost:49336/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49336/tests/dromaeo/cssquery-yui.html) 12:25:04 INFO - PID 1936 | RSS: Main: 273342464 12:25:04 INFO - PID 1936 | 12:28:06 INFO - PID 1936 | Cycle 1(1): loaded http://localhost:49336/tests/dromaeo/cssquery-yui.html (next: http://localhost:49336/tests/dromaeo/cssquery-dojo.html) 12:28:06 INFO - PID 1936 | RSS: Main: 279871488 12:28:06 INFO - PID 1936 | 12:28:06 INFO - PID 1936 | __start_tp_report 12:28:06 INFO - PID 1936 | _x_x_mozilla_page_load 12:28:06 INFO - PID 1936 | _x_x_mozilla_page_load_details 12:28:06 INFO - PID 1936 | |i|pagename|runs| 12:28:06 INFO - PID 1936 | |0;dojo.html;3259.3970115528614;3600.9099772505688;3622.5834029086664;3638.7998660073686;3656.945145822813;5464.26232458618;6348.936510634898;6356.3325850785695;6371.203599550056;6400.74397024119;6310.873782524347;6424.132742079818;6434.7747828826;6481.513886458522;6483.805485835433;4952.737052051722;5625.38120806711;5642.238297829802;5652.858678533039;5806.82579522615;5669.773209071612;5744.454276843693;5763.481286684198;5769.653820770746;5769.740361683714;5528;5579.637323573955;5609.719514024282;5670.234518340013;5677.034904066319;5601.29983752031;5611.298587676541;5621.213030175779;5640.971795141039;5654.208410822488;5478.753456094445;5479.904019196177;5532.75102620383;5584.078627026536;5585.162225666142;6242.063690446424;6246.375362463763;6325.240971083454;6347;6360.968195159041;6378.744850205986;7012.123484564429;7024.297570242986;7026.578405295648;7036.366726994595;7077.363037326665;7114.786556403316;7124.5012849100585;7142.14294284684;7171.497995140342;6514.837129071764;6517.9674101630135;6544.869102617921;6546.476281897437;6600.636964966973;7041.823954401181;7097.00641910133;7172.354488096096;7266.890996635055;7288.9271107289305;5078.847634570969;5581.6930068846605;6819.420349270367;6831.077804496329;6879.58722476653;7111.502194846415;7114.64426778659;7122.786316410516;7145.249748776408;7149.785506434815;5790.507806836382;6951.443884489228;6988.091548098715;7004.859902801915;7010.263922288086;6812.523123381415;6918.79243622682;6919.065926099912;6984.02223688684;6998.650067496605;5418.674879507243;5419.485148910847;5432.348118225759;5487.2317875497465;5530.889382212414;3959.8812035638975;4878.56092951636;4908.460069392364;4919.3604831371695;4955.380577427822;6520.315366886503;6531.673416329165;6547.443467305237;6547.574407663486;6561.114249576246;7110.57336559808;7113.288671132948;7126.2873712628325;7132.393746531499;7151.89272160918;6393.136926514954;6473.967630161819;6531.575447595891;6551.737930482823;6570.178727659043;5746.82190151018;6080.118382834465;6088.056351265561;6123.265208174958;6159.72281247336;6438.742450301936;7224.169220539495;7251.1298644163035;7254.310840470147;7290.854182916206;6948.965255173894;7074.752383666547;7082.433405327459;7104;7117.252688467844;7162.427005839624;7199.06412166436;7209.567425954249;7209.855802884018;7213.891791623025;6756.831079223059;6868.107146070979;6903.689333980084;6951.2353641098425;6973.12835895513;6574.980877963923;7142.821429464305;7163.641817909188;7165.390941770008;7173.820654483679;6646.4350530205475;6717.731290748316;6881.13985751781;6898.344657257552;6927.6882540286815;5354.330708661418;6527.706253218522;6651.600903945779;6738.460923126237;6861.862762744618;6043.184170137151;6686.398224159849;6776.525643205126;6788.253292137762;6807.285235050446;5964.8807023860145;6870.793876183722;6896.379325860697;6913.688884000333;6940.514164008471;6700.430463410665;6751.324867513209;6767.93232067673;6770.763023294358;6785.287544807724 12:28:06 INFO - PID 1936 | |1;ext.html;892.1301730812456;913.8393946790335;959.91840693541;974.6539978307705;976.6581696406258;38525.61474385255;39445.81662550121;39593.01017474557;39634.207315853666;39637;39486.01284967877;39598.80200599;39766.80116599413;39796.403053954215;39927.800360998226;7626.275503827142;8040.316573091294;8046.275835174833;8047.919520804776;8052.315553177958;7886.447948643568;7911.604419778988;7948;7959.482633628824;7981.760547183565;7882.487638303521;7882.881756773653;7891.210878912091;7896.407769417288;7903.249191326815;7849.254320839511;8484;8710.564471776386;8728.25809806161;8728.694495692684;8539.18877706623;8552.572371381406;8565.271951884097;8602.784930376729;8604;36860;36939;37052.81473592642;37125.814370928245;37141.62858371409;3614.331348700499;3622.6558476944647;3649.1242101895355;3652.543432070991;3656.4149736042104;30380.088597342114;30414.391712165634;30485.695143048728;30509;30527.626256818497;3062.081375587315;3171.1120886151916;3181.1251905726112;3182.745380369565;3183.9522407163913;235357;297328;319533;321775;322712;49578.504214957386;49745;49782.50217497779;49875.75062124666;49883.002339953;51860.48139518557;51954.74022629863;52145;52160.21759673608;52248.477515225124;14562.490312839;14715.484958026418;14720.558383248519;14724.779128313085;14731.263436828116;9185.311101667401;11308.378039207759;11572.900574445413;12115.939420302842;12121.454534545966;11684.824727629093;12806;13493.122947008413;13514.121582097132;13622.795658065137;5654.321481422174;5982.73077711504;6015.368386319461;6018.307894592091;6046.123312119718;907.1291560102376;985.9014098590227;1014.8579198912158;1033.235405799703;1073.8013467508536;19636;23354.416139596648;23490.295291141294;23506.529869402515;32865.68537258531;1143.8455808465917;1174.5477990973366;1180.1031216275692;1182.1665725663386;1186.4838795123976;12678.239305641506;12761;12811.167274127152;12871.935640321926;12945.09384343087;347652;350316;350550;350609;351308;55122.72438637861;55175.724121379135;55459.445405546234;55475.722621387446;55544.72227638836;1506.359797086234;1538.415402147177;1580.4942418426224;1585.310389980339;1588.3011474950983;17158.65682686314;21736.130554778265;22671.546569068196;23644.8817755917;24328.878355608107;39135.21729565336;39241.018974524726;39326.01684957899;39377;39529.407058893565;307693;307763;308411;308628;309915;31799.841000795772;32455.513167302037;32467.35065298728;36079.639203608676;36299.45550816687;43048.78475607727;44498.3325250128;44504.55495445004;44632.55367446284;45095;28067.696777338697;28499.857500712365;29118;29294.952815342574;29364;26314.21057368282;27437.31406714848;30011;30528.847355763966;31210.68789312078;37382.2523549522;37525.43711844379;37529.81235093807;37549.68576099823;37552.624473756005;27237.45525089527;29966;30127.548086779156;32078.679213208503;32193.35613287768;22578.548429031656;23221.187258445872;24312.784360781538;24607;24616.876915616023 12:28:06 INFO - PID 1936 | |2;jquery.html;571.7284289962269;576.85290250986;580.4311774461025;584.5878655547839;595.8599729063669;23894.76105238949;24397.268081957525;24847.503049939012;24887.880045397957;24917.87541062292;20730;24456;25523;26919.865400673018;27004.864975675144;6626.76806311778;6826.795196144099;6828.180618325808;6843.076184715074;6843.178818541782;6771.4921380896385;6827.624480653562;6828.453723702092;6832.214295356023;6846.5892046477375;6235.469985051276;6244.0633904914175;6549.6070235786;6572.638504882229;6810.387065164135;6528.673566321665;6618.106555614979;6648.734050637969;6656.26781054086;6656.7670131545165;6164.075388691687;6185.536084793658;6191.318954914956;6206.317305096436;6207.379262073757;27389;30486.085417437513;30932.536011959837;31059.84470077635;32153.874614388616;2179.716636837201;2218.179273668753;2242.1816037146486;2247.4943137793866;2249.2689875790434;16588.25352859124;17176.227069781766;18356.449306520826;18871.716924246146;18961.43115706531;2243.349428665685;2248.6851840742147;2284.463151159476;2295.185209250725;2304.9423764405856;210297;215204;216500.9174954115;217290;219088;16878.324867005183;17074.40239591633;17290.13549322554;17367.565810854576;17448.825511744973;18520.536986575444;18689.53276168107;23224.187153449548;23242.070317187463;23248.186313479284;10316.690499285034;10424.530896109542;10450.68647940563;10478.63324783629;10610.310329828537;7754.495957762728;9366.297527685314;9478.052194780466;9509.476978766095;9513.524323783784;9570.947195808456;9687.128158465772;9713;9741;9809.460479673686;2334.3230463165833;2723.618693382928;2747.2719729271757;2777.4583956128363;2873.6264285642733;958.4776296918056;959.5825815770162;970.8058388322364;1006.5475419311684;1016.8712728870947;20791.8960405197;21681.457963551038;21719.348419547438;22023.559528809335;22606.434839129153;863.7236084453041;903.7605034665819;932.9300302477205;974.4153507895206;978.5841017567506;11085.390303533222;11339.659810205707;11443.370614616277;11584.652460426201;11679.649610511698;569831;582280;587537;596655;598286.0085699543;159706;159980;163185;163728;164811;1145.8854114588476;1213.7997230457052;1237.7400745843295;1247.7005518675635;1253.1666441816271;19376.12807423641;19834.00829958525;20169.39491815248;20451.693224601346;20767.169313227303;70743.64628176826;71063;72984;73131;74573;90235;90493.64259535981;94380;95378.09243814946;95839.52080239553;12502.187357821713;12536.05979551519;12703.93648031754;12709;12721.363931803558;18889.866608003565;19086.33197838069;19498.512537186685;19516.707249391555;19563.926952028338;14969.850301497281;15292.61768455753;15663.295151718428;15702.528924132293;15852.60368490797;12357.382130893599;13425.932870335977;14260.786088208477;14390.352434140279;14732.852671473149;14538.709225815635;15253.694926101638;15770.369185232463;16403.015819050896;16560.923539969863;12445.688857778267;12498.875011249771;12566;12625.81061284063;12669.049821263257;15388.538343849703;15665.216739162768;15819;15843.970141940737;15856.44502442409 12:28:06 INFO - PID 1936 | |3;mootools.html;1469.5370958148183;1499.8350181480027;1537.0316700478697;1537.723689337849;1571.4892659885534;14942.25288735563;15573.610659733513;15599.142047187428;15616.984895981754;15639.687206255869;9640.325177237597;12776.936115319433;13121.868781312183;14896.851031489681;15388.076715397085;2838.5316422790215;2890.2774306423394;2893.6527616686026;2895.348546577014;2903.8693258803405;2835.9291017724618;2839.687634360219;2860.957085643717;2879.7264259895383;2888.6533615966114;2840.1905456944774;2855.414639998806;2861.3704984903393;2878.841663708495;2879.17943386135;2726.4410795786916;2820.2244382794793;2842.7725781937497;2926.6634337051087;2932.618759561243;2306.261996161229;2916.3000879788697;2925.7220564046384;2926.0344086451632;2928.926776830575;15197.544073677807;15244;15299.923500382427;15310;15317.46388876395;8153.470024448392;8399.5380254086;8468.66125354985;8487;8528.573571321409;4858.927116093261;5145.588352931794;5282.65662731925;5343.519083282485;5377.7311134443125;1429.371076726237;1507.3970411835353;1507.8869084818573;1526.7709843523448;1533;23687.052517899265;23809;23857.642135367983;24077;24082.63876041843;7279.67241474135;7310.4517161213125;7365.15300740411;7412.629368531552;7428.814279643052;15925.920370398306;15972.361105555648;15988.76016859748;16002.11988340629;16007.59981000461;8029.638666260033;8110.878336824952;8205.79485512867;8220.219079187467;8236.423450358418;7307.8538429232185;7483.4013278937555;7504.962475187698;7564.735234266775;7577.128630207487;5280.419153893069;5485.643433176911;5490.615656903979;5511.807086751945;5549.805756798492;1489.3000289863746;1497.3037537545094;1500.2423775993054;1501.7747337899402;1507.0957425544586;838.9035260945069;841.852675781748;868.6959564152496;875.5928493250669;887.5695287785419;7663.655135518915;8301.8339633207;8436.28291595209;8649.697260595849;8791.252743516745;963.8409662405625;976.9511524423892;1004.7287232447344;1006.3861044762689;1035.1615191694752;4861.246506791453;5107.489251074937;5237.292965449616;5367.677939323575;5483.341998960151;19904.402867913985;20664.380068597966;20799.27202547934;20919.37241882746;21043.789562104488;19918;20679.896600516902;20721.58556828855;20853.270135545183;20857.165713371294;10094.596216151418;10377.429241391645;10382.065614094618;10402.921654675658;10454.42500662471;6847;7196.748113815991;7473.775786726407;7959.721409750631;8462.8575142355;18184.545386365447;18619.534511636775;18635.347762828234;18702.6259474807;18951.14719837583;16967.575810604834;17787;17892;18795.81204187995;19090.713639295143;11722.648320550397;11737.295762254293;11871.228370156257;11982.700432489257;12037.217580857216;13633.250171240677;13740.519081832088;13741.381637826122;13749.175049497062;13751.724965500835;9724.222062234863;10556.577736890438;10658.307210031324;10914.454277286262;10969.780604388026;10437.32157409766;10452.477376131012;10651.147908167173;10823.783524329627;11021.669349919515;9848.310618256653;9896.455694936847;9954.104130628279;9960.701178964642;9973.3018688694;7078.68145933424;7105.644717764195;7147.463940204401;7396.96301518489;7510.849783004419;7137.50037497391;7332.738451280157;7549.773506794805;7579.924200757921;7637 12:28:06 INFO - PID 1936 | |4;prototype.html;466.85527486479197;486.77608300181913;490.1080034337505;510.42322175941183;512.915368964121;4717.033008233312;5860.765569377219;6160.260768707756;6224.315325314217;6490.415862572368;4464.620507256887;5738.454846789548;5757.539396848252;6027.849303767408;6422.357764223575;4303.698741088109;4969.527894849993;5021.673591216578;5027.622928280375;5037.521435463644;4971.204607262839;4985.750712464381;4998.900021999558;5003.598992282148;5030.2957585938;4529.456465224178;4675.485696573374;4980.427250866161;4989.501049895;5004.249362595603;4328.372386004027;4384.320430333271;4520.434945631796;4546.636269098464;4895.21676531755;4214.620684138442;4217.114405974749;4218.388333691628;4231.97884010581;4232.87301380956;6181.629102253879;6184.443400094013;6196.132541444156;6206.844828879269;6213.00591905293;5734.996375634248;5741.9951508486265;5751.626144300607;5755.65466072037;5788.5369170466265;6871.415929645986;6882.380585747257;6884.862302753967;6895.689693963784;6909.099997500677;4079.8980025499304;4201.411802347674;4272.4445822042935;4893.755312234374;4904.215325547895;5866.911996320058;7103.218645948941;7217.350438460511;7292.505036467512;7299.781006569864;4642.326862604903;4925.458199598113;4930.630202734809;4982.676126051795;4989.675671081368;4373.769377617438;4960.528749768839;5079.593632509391;5102.36220472441;5118.923216151761;4838.080764654705;5745.626534275259;5751.626144300607;5768;5813.651180929158;6897.41371983377;6942.479314051467;6944.895826562504;6949.235584085746;6984.476164287698;1572.842715728418;1636.8117666468509;1740.3908631978707;1743.8779285449896;1752.4392194497893;1260.3005332040623;1330.6540299522;1377.8208832851665;1412.3573773932835;1412.7951447040123;5048.3184770056305;5906.940930590725;6381.1704478417505;6422.74309027643;6448.903266451007;5795.8551036224435;7028.156621205386;7029.26192749754;7084.752033678797;7086.433085353159;5580.274564306615;6361.61830290184;6371.840703982345;6384.648844313608;6402.775902843471;3638.872639457606;3646.4165733482514;3655.569046584045;3666.5600127984812;3672.127215120308;6732.225794033749;7223.783286501413;7224.74713385029;7231.240719724458;7242.891356629655;6500.959846424549;6807.931920680829;7104.6447677615915;7287.599182045038;7334.523255988343;7183.748568800067;7221.855562888818;7255.2019277878335;7263.745768898063;7328.78013659591;7020.403265722471;7026.297370262932;7034.894476582957;7048.695991241637;7064.187618423947;6407.583507072025;6937.89593156093;6958.652067396712;6961.02286864894;6978.930210697828;6772.796816095525;7270.418366530771;7326.743563975236;7368.557886526825;7369.978308893543;4383.495897971647;4979.775910083982;4984.975075124601;4997;5005.649604527648;6440.776252512008;6688;6956.5478243913985;7107.644617769194;7134.215236324104;6580.243272023778;6765;6822.249552549117;6835.4189893859575;6844.384005439534;6443.387878151568;6588.505862060269;6785.491088168308;6804.931950680628;6808.795736127924;6610.636414997221;6614.867702645824;6661.267260601233;6678.398944094861;6746.69639866217;5951;6213.813585592439;6568.080468734476;6699.899501507579;6881.799963017221;5769.653820770767;5786.627482732059;6642.667866606554;6676.799696009128;6678.632675202911 12:28:06 INFO - PID 1936 | |5;yui.html;859.6511117792907;872.8254349130174;879.2131042716765;880.8065071827673;898.0435835834835;16751.32994680213;17633.470995870146;17700;17708.114594270282;17723.556911077212;15726.528204153894;15738.370465181408;15767.053976761374;16416.425425110123;18329.54176145597;1042.1298215989657;1064.0423618743155;1065.7815147894662;1067.3649178738638;1067.898549637787;1046.748780292732;1050.9894901051007;1057.5399701129995;1057.7302787789104;1057.746140926176;1105.5190991918541;1114.9777004459906;1120.1598800899326;1120.9607663731772;1121.2375584602466;904.2946501728662;993.2401712689834;1088.0914436445498;1110.8500352452436;1112.9888701113007;787.2481779900181;788.9250521200477;793.670626689923;793.7698067560401;796.6176235406979;18416.631667366713;18420.263189472404;18506.722399164024;18683.90658046701;18701.064946752744;17998.460046198634;18522.07389630513;18598.999999999865;18618.720719189223;18728.250869965184;15052.096874187475;15352.232388380537;15361.846381536154;15414.922925385414;15489.92255038729;3703.1112532992156;3756.380197267448;3775.546934367866;3779.225258821935;3793.3740932746064;120175.99999999913;137803.9086286801;157954.31251281174;161289;163098;14415.78376324356;14525.854741452451;14559.85440145585;14706.926465367605;14843.03520271179;25360.492790144464;25819.870900645376;27215.319617009733;27557.071005029855;28097.71902280992;6939.23668396476;8268.503889766633;8295.8340833183;8336.541490218096;8354.707585234488;4051.716379853382;4077.673786097105;4096.9795151024055;4127.628513433805;4131.111810960658;4176.937345939813;4913.87715307113;5089.898202035939;5117.155669314521;5132.538071573502;1101.130107215307;1124.3478782306242;1132.105636547135;1135.0465608888574;1145.1869172887177;658.2627457247913;669.5915491550151;669.6183175589867;686.9798349451061;693.774523279936;10270.794584108276;11682.890125438069;12451.750964980649;12794.872051279553;12991.220526768235;603.6287683074877;628.4595248086641;655.4657953767664;666.5733930284622;673.515069150211;5264.973330200651;5461.371942226615;5586.134149206799;5709.257796486513;5829.271341082364;120459;148517;155009.2249538745;156221.2188939048;158227;133524;136703.31648341694;137129;137148;138011;80909;86955;95760.52119739357;111550.44224778986;114812.8518714819;9095.090490951117;12284.262944223376;12339.814902776645;12432.12975091735;12618.621441356774;51804.740976294874;52862;53337.19994200168;53392;53653.463465364846;71283.84601215759;71601.64199178971;74984.62507687426;75747.62126189334;76189.6190519066;16665.750013750046;17086.48740537786;17380.957142571486;17634.823651763832;17666.02836843986;18933.526661833563;19079.869336458396;19153.52116197106;19166.329178478685;19257.614847703248;14473.421063157777;17354.5661358462;17362.91318543399;17503.94976301426;17800.931944083397;16745.33018679288;17234.052127133127;17866.642667146843;17890.642187156445;18112.637747244717;17076.743848842525;17179.570510737332;17319.220635071702;17494.125293735517;17727;12002.939985300018;12052.638420847388;12173.573924912585;12843.165194262343;13189.604311870658;13127.606171814861;13525.59423217305;13580.524681636096;13729.588112356645;13796.724065518834 12:28:06 INFO - PID 1936 | __end_tp_report 12:28:06 INFO - PID 1936 | __start_cc_report 12:28:06 INFO - PID 1936 | _x_x_mozilla_cycle_collect,439 12:28:06 INFO - PID 1936 | __end_cc_report 12:28:06 INFO - PID 1936 | __startTimestamp1498073286741__endTimestamp 12:28:06 INFO - PID 1936 | 12:28:06 INFO - PID 1936 | ------- Summary: start ------- 12:28:06 INFO - PID 1936 | Number of tests: 6 12:28:06 INFO - PID 1936 | 12:28:06 INFO - PID 1936 | [#0] dojo.html Cycles:165 Average:6373.56 Median:6570.18 stddev:831.89 (12.7%) stddev-sans-first:797.77 12:28:06 INFO - PID 1936 | Values: 3259.4 3600.9 3622.6 3638.8 3656.9 5464.3 6348.9 6356.3 6371.2 6400.7 6310.9 6424.1 6434.8 6481.5 6483.8 4952.7 5625.4 5642.2 5652.9 5806.8 5669.8 5744.5 5763.5 5769.7 5769.7 5528.0 5579.6 5609.7 5670.2 5677.0 5601.3 5611.3 5621.2 5641.0 5654.2 5478.8 5479.9 5532.8 5584.1 5585.2 6242.1 6246.4 6325.2 6347.0 6361.0 6378.7 7012.1 7024.3 7026.6 7036.4 7077.4 7114.8 7124.5 7142.1 7171.5 6514.8 6518.0 6544.9 6546.5 6600.6 7041.8 7097.0 7172.4 7266.9 7288.9 5078.8 5581.7 6819.4 6831.1 6879.6 7111.5 7114.6 7122.8 7145.2 7149.8 5790.5 6951.4 6988.1 7004.9 7010.3 6812.5 6918.8 6919.1 6984.0 6998.7 5418.7 5419.5 5432.3 5487.2 5530.9 3959.9 4878.6 4908.5 4919.4 4955.4 6520.3 6531.7 6547.4 6547.6 6561.1 7110.6 7113.3 7126.3 7132.4 7151.9 6393.1 6474.0 6531.6 6551.7 6570.2 5746.8 6080.1 6088.1 6123.3 6159.7 6438.7 7224.2 7251.1 7254.3 7290.9 6949.0 7074.8 7082.4 7104.0 7117.3 7162.4 7199.1 7209.6 7209.9 7213.9 6756.8 6868.1 6903.7 6951.2 6973.1 6575.0 7142.8 7163.6 7165.4 7173.8 6646.4 6717.7 6881.1 6898.3 6927.7 5354.3 6527.7 6651.6 6738.5 6861.9 6043.2 6686.4 6776.5 6788.3 6807.3 5964.9 6870.8 6896.4 6913.7 6940.5 6700.4 6751.3 6767.9 6770.8 6785.3 12:28:06 INFO - PID 1936 | 12:28:06 INFO - PID 1936 | [#1] ext.html Cycles:180 Average:46816.54 Median:19846.99 stddev:84338.94 (424.9%) stddev-sans-first:84505.05 12:28:06 INFO - PID 1936 | Values: 892.1 913.8 959.9 974.7 976.7 38525.6 39445.8 39593.0 39634.2 39637.0 39486.0 39598.8 39766.8 39796.4 39927.8 7626.3 8040.3 8046.3 8047.9 8052.3 7886.4 7911.6 7948.0 7959.5 7981.8 7882.5 7882.9 7891.2 7896.4 7903.2 7849.3 8484.0 8710.6 8728.3 8728.7 8539.2 8552.6 8565.3 8602.8 8604.0 36860.0 36939.0 37052.8 37125.8 37141.6 3614.3 3622.7 3649.1 3652.5 3656.4 30380.1 30414.4 30485.7 30509.0 30527.6 3062.1 3171.1 3181.1 3182.7 3184.0 235357.0 297328.0 319533.0 321775.0 322712.0 49578.5 49745.0 49782.5 49875.8 49883.0 51860.5 51954.7 52145.0 52160.2 52248.5 14562.5 14715.5 14720.6 14724.8 14731.3 9185.3 11308.4 11572.9 12115.9 12121.5 11684.8 12806.0 13493.1 13514.1 13622.8 5654.3 5982.7 6015.4 6018.3 6046.1 907.1 985.9 1014.9 1033.2 1073.8 19636.0 23354.4 23490.3 23506.5 32865.7 1143.8 1174.5 1180.1 1182.2 1186.5 12678.2 12761.0 12811.2 12871.9 12945.1 347652.0 350316.0 350550.0 350609.0 351308.0 55122.7 55175.7 55459.4 55475.7 55544.7 1506.4 1538.4 1580.5 1585.3 1588.3 17158.7 21736.1 22671.5 23644.9 24328.9 39135.2 39241.0 39326.0 39377.0 39529.4 307693.0 307763.0 308411.0 308628.0 309915.0 31799.8 32455.5 32467.4 36079.6 36299.5 43048.8 44498.3 44504.6 44632.6 45095.0 28067.7 28499.9 29118.0 29295.0 29364.0 26314.2 27437.3 30011.0 30528.8 31210.7 37382.3 37525.4 37529.8 37549.7 37552.6 27237.5 29966.0 30127.5 32078.7 32193.4 22578.5 23221.2 24312.8 24607.0 24616.9 12:28:06 INFO - PID 1936 | 12:28:06 INFO - PID 1936 | [#2] jquery.html Cycles:180 Average:41633.84 Median:12427.85 stddev:102401.22 (824.0%) stddev-sans-first:102642.07 12:28:06 INFO - PID 1936 | Values: 571.7 576.9 580.4 584.6 595.9 23894.8 24397.3 24847.5 24887.9 24917.9 20730.0 24456.0 25523.0 26919.9 27004.9 6626.8 6826.8 6828.2 6843.1 6843.2 6771.5 6827.6 6828.5 6832.2 6846.6 6235.5 6244.1 6549.6 6572.6 6810.4 6528.7 6618.1 6648.7 6656.3 6656.8 6164.1 6185.5 6191.3 6206.3 6207.4 27389.0 30486.1 30932.5 31059.8 32153.9 2179.7 2218.2 2242.2 2247.5 2249.3 16588.3 17176.2 18356.4 18871.7 18961.4 2243.3 2248.7 2284.5 2295.2 2304.9 210297.0 215204.0 216500.9 217290.0 219088.0 16878.3 17074.4 17290.1 17367.6 17448.8 18520.5 18689.5 23224.2 23242.1 23248.2 10316.7 10424.5 10450.7 10478.6 10610.3 7754.5 9366.3 9478.1 9509.5 9513.5 9570.9 9687.1 9713.0 9741.0 9809.5 2334.3 2723.6 2747.3 2777.5 2873.6 958.5 959.6 970.8 1006.5 1016.9 20791.9 21681.5 21719.3 22023.6 22606.4 863.7 903.8 932.9 974.4 978.6 11085.4 11339.7 11443.4 11584.7 11679.6 569831.0 582280.0 587537.0 596655.0 598286.0 159706.0 159980.0 163185.0 163728.0 164811.0 1145.9 1213.8 1237.7 1247.7 1253.2 19376.1 19834.0 20169.4 20451.7 20767.2 70743.6 71063.0 72984.0 73131.0 74573.0 90235.0 90493.6 94380.0 95378.1 95839.5 12502.2 12536.1 12703.9 12709.0 12721.4 18889.9 19086.3 19498.5 19516.7 19563.9 14969.9 15292.6 15663.3 15702.5 15852.6 12357.4 13425.9 14260.8 14390.4 14732.9 14538.7 15253.7 15770.4 16403.0 16560.9 12445.7 12498.9 12566.0 12625.8 12669.0 15388.5 15665.2 15819.0 15844.0 15856.4 12:28:06 INFO - PID 1936 | 12:28:06 INFO - PID 1936 | [#3] mootools.html Cycles:180 Average:9096.73 Median:2839.11 stddev:6259.79 (220.5%) stddev-sans-first:6251.12 12:28:06 INFO - PID 1936 | Values: 1469.5 1499.8 1537.0 1537.7 1571.5 14942.3 15573.6 15599.1 15617.0 15639.7 9640.3 12776.9 13121.9 14896.9 15388.1 2838.5 2890.3 2893.7 2895.3 2903.9 2835.9 2839.7 2861.0 2879.7 2888.7 2840.2 2855.4 2861.4 2878.8 2879.2 2726.4 2820.2 2842.8 2926.7 2932.6 2306.3 2916.3 2925.7 2926.0 2928.9 15197.5 15244.0 15299.9 15310.0 15317.5 8153.5 8399.5 8468.7 8487.0 8528.6 4858.9 5145.6 5282.7 5343.5 5377.7 1429.4 1507.4 1507.9 1526.8 1533.0 23687.1 23809.0 23857.6 24077.0 24082.6 7279.7 7310.5 7365.2 7412.6 7428.8 15925.9 15972.4 15988.8 16002.1 16007.6 8029.6 8110.9 8205.8 8220.2 8236.4 7307.9 7483.4 7505.0 7564.7 7577.1 5280.4 5485.6 5490.6 5511.8 5549.8 1489.3 1497.3 1500.2 1501.8 1507.1 838.9 841.9 868.7 875.6 887.6 7663.7 8301.8 8436.3 8649.7 8791.3 963.8 977.0 1004.7 1006.4 1035.2 4861.2 5107.5 5237.3 5367.7 5483.3 19904.4 20664.4 20799.3 20919.4 21043.8 19918.0 20679.9 20721.6 20853.3 20857.2 10094.6 10377.4 10382.1 10402.9 10454.4 6847.0 7196.7 7473.8 7959.7 8462.9 18184.5 18619.5 18635.3 18702.6 18951.1 16967.6 17787.0 17892.0 18795.8 19090.7 11722.6 11737.3 11871.2 11982.7 12037.2 13633.3 13740.5 13741.4 13749.2 13751.7 9724.2 10556.6 10658.3 10914.5 10969.8 10437.3 10452.5 10651.1 10823.8 11021.7 9848.3 9896.5 9954.1 9960.7 9973.3 7078.7 7105.6 7147.5 7397.0 7510.8 7137.5 7332.7 7549.8 7579.9 7637.0 12:28:06 INFO - PID 1936 | 12:28:06 INFO - PID 1936 | [#4] prototype.html Cycles:180 Average:5584.46 Median:6183.04 stddev:1679.98 (27.2%) stddev-sans-first:1640.20 12:28:06 INFO - PID 1936 | Values: 466.9 486.8 490.1 510.4 512.9 4717.0 5860.8 6160.3 6224.3 6490.4 4464.6 5738.5 5757.5 6027.8 6422.4 4303.7 4969.5 5021.7 5027.6 5037.5 4971.2 4985.8 4998.9 5003.6 5030.3 4529.5 4675.5 4980.4 4989.5 5004.2 4328.4 4384.3 4520.4 4546.6 4895.2 4214.6 4217.1 4218.4 4232.0 4232.9 6181.6 6184.4 6196.1 6206.8 6213.0 5735.0 5742.0 5751.6 5755.7 5788.5 6871.4 6882.4 6884.9 6895.7 6909.1 4079.9 4201.4 4272.4 4893.8 4904.2 5866.9 7103.2 7217.4 7292.5 7299.8 4642.3 4925.5 4930.6 4982.7 4989.7 4373.8 4960.5 5079.6 5102.4 5118.9 4838.1 5745.6 5751.6 5768.0 5813.7 6897.4 6942.5 6944.9 6949.2 6984.5 1572.8 1636.8 1740.4 1743.9 1752.4 1260.3 1330.7 1377.8 1412.4 1412.8 5048.3 5906.9 6381.2 6422.7 6448.9 5795.9 7028.2 7029.3 7084.8 7086.4 5580.3 6361.6 6371.8 6384.6 6402.8 3638.9 3646.4 3655.6 3666.6 3672.1 6732.2 7223.8 7224.7 7231.2 7242.9 6501.0 6807.9 7104.6 7287.6 7334.5 7183.7 7221.9 7255.2 7263.7 7328.8 7020.4 7026.3 7034.9 7048.7 7064.2 6407.6 6937.9 6958.7 6961.0 6978.9 6772.8 7270.4 7326.7 7368.6 7370.0 4383.5 4979.8 4985.0 4997.0 5005.6 6440.8 6688.0 6956.5 7107.6 7134.2 6580.2 6765.0 6822.2 6835.4 6844.4 6443.4 6588.5 6785.5 6804.9 6808.8 6610.6 6614.9 6661.3 6678.4 6746.7 5951.0 6213.8 6568.1 6699.9 6881.8 5769.7 5786.6 6642.7 6676.8 6678.6 12:28:06 INFO - PID 1936 | 12:28:06 INFO - PID 1936 | [#5] yui.html Cycles:180 Average:26602.56 Median:17704.06 stddev:41009.44 (231.6%) stddev-sans-first:41078.93 12:28:06 INFO - PID 1936 | Values: 859.7 872.8 879.2 880.8 898.0 16751.3 17633.5 17700.0 17708.1 17723.6 15726.5 15738.4 15767.1 16416.4 18329.5 1042.1 1064.0 1065.8 1067.4 1067.9 1046.7 1051.0 1057.5 1057.7 1057.7 1105.5 1115.0 1120.2 1121.0 1121.2 904.3 993.2 1088.1 1110.9 1113.0 787.2 788.9 793.7 793.8 796.6 18416.6 18420.3 18506.7 18683.9 18701.1 17998.5 18522.1 18599.0 18618.7 18728.3 15052.1 15352.2 15361.8 15414.9 15489.9 3703.1 3756.4 3775.5 3779.2 3793.4 120176.0 137803.9 157954.3 161289.0 163098.0 14415.8 14525.9 14559.9 14706.9 14843.0 25360.5 25819.9 27215.3 27557.1 28097.7 6939.2 8268.5 8295.8 8336.5 8354.7 4051.7 4077.7 4097.0 4127.6 4131.1 4176.9 4913.9 5089.9 5117.2 5132.5 1101.1 1124.3 1132.1 1135.0 1145.2 658.3 669.6 669.6 687.0 693.8 10270.8 11682.9 12451.8 12794.9 12991.2 603.6 628.5 655.5 666.6 673.5 5265.0 5461.4 5586.1 5709.3 5829.3 120459.0 148517.0 155009.2 156221.2 158227.0 133524.0 136703.3 137129.0 137148.0 138011.0 80909.0 86955.0 95760.5 111550.4 114812.9 9095.1 12284.3 12339.8 12432.1 12618.6 51804.7 52862.0 53337.2 53392.0 53653.5 71283.8 71601.6 74984.6 75747.6 76189.6 16665.8 17086.5 17381.0 17634.8 17666.0 18933.5 19079.9 19153.5 19166.3 19257.6 14473.4 17354.6 17362.9 17503.9 17800.9 16745.3 17234.1 17866.6 17890.6 18112.6 17076.7 17179.6 17319.2 17494.1 17727.0 12002.9 12052.6 12173.6 12843.2 13189.6 13127.6 13525.6 13580.5 13729.6 13796.7 12:28:06 INFO - PID 1936 | -------- Summary: end -------- 12:28:06 INFO - PID 1936 | 12:28:07 INFO - TEST-INFO | 1936: exit 0 12:28:07 INFO - TEST-OK | dromaeo_css | took 1118153ms 12:28:07 INFO - TEST-START | kraken 12:28:07 INFO - operating with platform_type : mac_ 12:28:07 INFO - Initialising browser for kraken test... 12:28:07 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49336/getInfo.html -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmp45VuKa/profile 12:28:07 INFO - TEST-INFO | started process 1942 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49336/getInfo.html -foreground) 12:28:11 INFO - TEST-INFO | 1942: exit 0 12:28:11 INFO - Browser initialized. 12:28:11 INFO - Running cycle 1/1 for kraken test... 12:28:11 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmp45VuKa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:28:13 INFO - PID 1948 | 2017-06-21 12:28:13.850 plugin-container[1949:15137] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x453f, name = 'com.apple.tsm.portname' 12:28:13 INFO - PID 1948 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:28:13 INFO - PID 1948 | 2017-06-21 12:28:13.851 plugin-container[1949:15137] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f13, name = 'com.apple.CFPasteboardClient' 12:28:13 INFO - PID 1948 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:28:13 INFO - PID 1948 | 2017-06-21 12:28:13.851 plugin-container[1949:15137] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:28:14 INFO - PID 1948 | 2017-06-21 12:28:14.452 plugin-container[1950:15188] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9643, name = 'com.apple.tsm.portname' 12:28:14 INFO - PID 1948 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:28:14 INFO - PID 1948 | 2017-06-21 12:28:14.453 plugin-container[1950:15188] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 12:28:14 INFO - PID 1948 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:28:14 INFO - PID 1948 | 2017-06-21 12:28:14.453 plugin-container[1950:15188] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:28:23 INFO - PID 1948 | RSS: Main: 249319424 12:28:23 INFO - PID 1948 | 12:28:26 INFO - PID 1948 | Cycle 1(1): loaded http://localhost:49336/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49336/tests/kraken/driver.html?testName=audio-beat-detection) 12:28:27 INFO - PID 1948 | RSS: Main: 260423680 12:28:27 INFO - PID 1948 | 12:28:30 INFO - PID 1948 | Cycle 1(1): loaded http://localhost:49336/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49336/tests/kraken/driver.html?testName=audio-dft) 12:28:31 INFO - PID 1948 | RSS: Main: 269926400 12:28:31 INFO - PID 1948 | 12:28:34 INFO - PID 1948 | Cycle 1(1): loaded http://localhost:49336/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49336/tests/kraken/driver.html?testName=audio-fft) 12:28:35 INFO - PID 1948 | RSS: Main: 272982016 12:28:35 INFO - PID 1948 | 12:28:36 INFO - PID 1948 | Cycle 1(1): loaded http://localhost:49336/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49336/tests/kraken/driver.html?testName=audio-oscillator) 12:28:37 INFO - PID 1948 | RSS: Main: 273285120 12:28:37 INFO - PID 1948 | 12:28:39 INFO - PID 1948 | Cycle 1(1): loaded http://localhost:49336/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49336/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:28:40 INFO - PID 1948 | RSS: Main: 275456000 12:28:40 INFO - PID 1948 | 12:28:43 INFO - PID 1948 | Cycle 1(1): loaded http://localhost:49336/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49336/tests/kraken/driver.html?testName=imaging-darkroom) 12:28:43 INFO - PID 1948 | RSS: Main: 282996736 12:28:43 INFO - PID 1948 | 12:28:46 INFO - PID 1948 | Cycle 1(1): loaded http://localhost:49336/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49336/tests/kraken/driver.html?testName=imaging-desaturate) 12:28:47 INFO - PID 1948 | RSS: Main: 284499968 12:28:47 INFO - PID 1948 | 12:28:50 INFO - PID 1948 | Cycle 1(1): loaded http://localhost:49336/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49336/tests/kraken/driver.html?testName=json-parse-financial) 12:28:50 INFO - PID 1948 | RSS: Main: 283549696 12:28:50 INFO - PID 1948 | 12:28:52 INFO - PID 1948 | Cycle 1(1): loaded http://localhost:49336/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49336/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:28:53 INFO - PID 1948 | RSS: Main: 283471872 12:28:53 INFO - PID 1948 | 12:28:54 INFO - PID 1948 | Cycle 1(1): loaded http://localhost:49336/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49336/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:28:55 INFO - PID 1948 | RSS: Main: 283561984 12:28:55 INFO - PID 1948 | 12:28:57 INFO - PID 1948 | Cycle 1(1): loaded http://localhost:49336/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49336/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:28:58 INFO - PID 1948 | RSS: Main: 283152384 12:28:58 INFO - PID 1948 | 12:29:00 INFO - PID 1948 | Cycle 1(1): loaded http://localhost:49336/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49336/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:29:01 INFO - PID 1948 | RSS: Main: 283357184 12:29:01 INFO - PID 1948 | 12:29:04 INFO - PID 1948 | Cycle 1(1): loaded http://localhost:49336/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49336/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:29:05 INFO - PID 1948 | RSS: Main: 283275264 12:29:05 INFO - PID 1948 | 12:29:07 INFO - PID 1948 | Cycle 1(1): loaded http://localhost:49336/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49336/tests/kraken/driver.html?testName=ai-astar) 12:29:07 INFO - PID 1948 | RSS: Main: 285081600 12:29:07 INFO - PID 1948 | 12:29:07 INFO - PID 1948 | __start_tp_report 12:29:07 INFO - PID 1948 | _x_x_mozilla_page_load 12:29:07 INFO - PID 1948 | _x_x_mozilla_page_load_details 12:29:07 INFO - PID 1948 | |i|pagename|runs| 12:29:07 INFO - PID 1948 | |0;ai-astar;107;112;109;114;147;169;166;114;116;115 12:29:07 INFO - PID 1948 | |1;audio-beat-detection;142;112;139;143;109;133;143;188;176;95 12:29:07 INFO - PID 1948 | |2;audio-dft;199;220;236;234;203;207;200;199;196;200 12:29:07 INFO - PID 1948 | |3;audio-fft;67;86;73;138;61;58;67;63;111;58 12:29:07 INFO - PID 1948 | |4;audio-oscillator;75;105;147;100;73;80;112;69;71;110 12:29:07 INFO - PID 1948 | |5;imaging-gaussian-blur;91;91;92;108;101;92;91;90;90;90 12:29:07 INFO - PID 1948 | |6;imaging-darkroom;98;97;98;97;97;99;98;98;99;97 12:29:07 INFO - PID 1948 | |7;imaging-desaturate;91;90;92;92;116;146;90;90;94;91 12:29:07 INFO - PID 1948 | |8;json-parse-financial;52;50;51;49;53;69;44;47;52;54 12:29:07 INFO - PID 1948 | |9;json-stringify-tinderbox;55;54;54;61;53;60;54;53;52;52 12:29:07 INFO - PID 1948 | |10;stanford-crypto-aes;94;93;96;95;104;101;69;91;71;94 12:29:07 INFO - PID 1948 | |11;stanford-crypto-ccm;134;143;158;134;132;135;140;140;137;159 12:29:07 INFO - PID 1948 | |12;stanford-crypto-pbkdf2;185;184;183;187;179;187;185;180;179;168 12:29:07 INFO - PID 1948 | |13;stanford-crypto-sha256-iterative;56;75;98;89;82;61;64;66;66;62 12:29:07 INFO - PID 1948 | __end_tp_report 12:29:07 INFO - PID 1948 | __start_cc_report 12:29:07 INFO - PID 1948 | _x_x_mozilla_cycle_collect,1092 12:29:07 INFO - PID 1948 | __end_cc_report 12:29:07 INFO - PID 1948 | __startTimestamp1498073347480__endTimestamp 12:29:07 INFO - PID 1948 | 12:29:07 INFO - PID 1948 | ------- Summary: start ------- 12:29:07 INFO - PID 1948 | Number of tests: 14 12:29:07 INFO - PID 1948 | 12:29:07 INFO - PID 1948 | [#0] ai-astar Cycles:10 Average:126.90 Median:115.50 stddev:24.12 (20.9%) stddev-sans-first:24.49 12:29:07 INFO - PID 1948 | Values: 107.0 112.0 109.0 114.0 147.0 169.0 166.0 114.0 116.0 115.0 12:29:07 INFO - PID 1948 | 12:29:07 INFO - PID 1948 | [#1] audio-beat-detection Cycles:10 Average:138.00 Median:143.00 stddev:28.68 (20.1%) stddev-sans-first:30.38 12:29:07 INFO - PID 1948 | Values: 142.0 112.0 139.0 143.0 109.0 133.0 143.0 188.0 176.0 95.0 12:29:07 INFO - PID 1948 | 12:29:07 INFO - PID 1948 | [#2] audio-dft Cycles:10 Average:209.40 Median:205.00 stddev:15.07 (7.4%) stddev-sans-first:15.51 12:29:07 INFO - PID 1948 | Values: 199.0 220.0 236.0 234.0 203.0 207.0 200.0 199.0 196.0 200.0 12:29:07 INFO - PID 1948 | 12:29:07 INFO - PID 1948 | [#3] audio-fft Cycles:10 Average:78.20 Median:65.00 stddev:26.53 (40.8%) stddev-sans-first:27.83 12:29:07 INFO - PID 1948 | Values: 67.0 86.0 73.0 138.0 61.0 58.0 67.0 63.0 111.0 58.0 12:29:07 INFO - PID 1948 | 12:29:07 INFO - PID 1948 | [#4] audio-oscillator Cycles:10 Average:94.20 Median:70.00 stddev:25.16 (35.9%) stddev-sans-first:25.71 12:29:07 INFO - PID 1948 | Values: 75.0 105.0 147.0 100.0 73.0 80.0 112.0 69.0 71.0 110.0 12:29:07 INFO - PID 1948 | 12:29:07 INFO - PID 1948 | [#5] imaging-gaussian-blur Cycles:10 Average:93.60 Median:91.00 stddev:6.02 (6.6%) stddev-sans-first:6.31 12:29:07 INFO - PID 1948 | Values: 91.0 91.0 92.0 108.0 101.0 92.0 91.0 90.0 90.0 90.0 12:29:07 INFO - PID 1948 | 12:29:07 INFO - PID 1948 | [#6] imaging-darkroom Cycles:10 Average:97.80 Median:98.00 stddev:0.79 (0.8%) stddev-sans-first:0.83 12:29:07 INFO - PID 1948 | Values: 98.0 97.0 98.0 97.0 97.0 99.0 98.0 98.0 99.0 97.0 12:29:07 INFO - PID 1948 | 12:29:07 INFO - PID 1948 | [#7] imaging-desaturate Cycles:10 Average:99.20 Median:91.00 stddev:18.23 (20.0%) stddev-sans-first:19.09 12:29:07 INFO - PID 1948 | Values: 91.0 90.0 92.0 92.0 116.0 146.0 90.0 90.0 94.0 91.0 12:29:07 INFO - PID 1948 | 12:29:07 INFO - PID 1948 | [#8] json-parse-financial Cycles:10 Average:52.10 Median:52.00 stddev:6.64 (12.8%) stddev-sans-first:7.04 12:29:07 INFO - PID 1948 | Values: 52.0 50.0 51.0 49.0 53.0 69.0 44.0 47.0 52.0 54.0 12:29:07 INFO - PID 1948 | 12:29:07 INFO - PID 1948 | [#9] json-stringify-tinderbox Cycles:10 Average:54.80 Median:54.00 stddev:3.16 (5.8%) stddev-sans-first:3.35 12:29:07 INFO - PID 1948 | Values: 55.0 54.0 54.0 61.0 53.0 60.0 54.0 53.0 52.0 52.0 12:29:07 INFO - PID 1948 | 12:29:07 INFO - PID 1948 | [#10] stanford-crypto-aes Cycles:10 Average:90.80 Median:93.50 stddev:11.62 (12.4%) stddev-sans-first:12.27 12:29:07 INFO - PID 1948 | Values: 94.0 93.0 96.0 95.0 104.0 101.0 69.0 91.0 71.0 94.0 12:29:07 INFO - PID 1948 | 12:29:07 INFO - PID 1948 | [#11] stanford-crypto-ccm Cycles:10 Average:141.20 Median:140.00 stddev:9.72 (6.9%) stddev-sans-first:9.95 12:29:07 INFO - PID 1948 | Values: 134.0 143.0 158.0 134.0 132.0 135.0 140.0 140.0 137.0 159.0 12:29:07 INFO - PID 1948 | 12:29:07 INFO - PID 1948 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.70 Median:184.50 stddev:5.68 (3.1%) stddev-sans-first:5.89 12:29:07 INFO - PID 1948 | Values: 185.0 184.0 183.0 187.0 179.0 187.0 185.0 180.0 179.0 168.0 12:29:07 INFO - PID 1948 | 12:29:07 INFO - PID 1948 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.90 Median:70.50 stddev:13.69 (19.4%) stddev-sans-first:13.26 12:29:07 INFO - PID 1948 | Values: 56.0 75.0 98.0 89.0 82.0 61.0 64.0 66.0 66.0 62.0 12:29:07 INFO - PID 1948 | -------- Summary: end -------- 12:29:07 INFO - PID 1948 | 12:29:08 INFO - TEST-INFO | 1948: exit 0 12:29:08 INFO - TEST-OK | kraken | took 60953ms 12:29:08 INFO - SUITE-END | took 1179s 12:29:08 INFO - Completed test suite (00:19:39) 12:29:08 INFO - javascript benchmark 12:29:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8654.273441547879, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3259.3970115528614, 3600.9099772505688, 3622.5834029086664, 3638.7998660073686, 3656.945145822813, 5464.26232458618, 6348.936510634898, 6356.3325850785695, 6371.203599550056, 6400.74397024119, 6310.873782524347, 6424.132742079818, 6434.7747828826, 6481.513886458522, 6483.805485835433, 4952.737052051722, 5625.38120806711, 5642.238297829802, 5652.858678533039, 5806.82579522615, 5669.773209071612, 5744.454276843693, 5763.481286684198, 5769.653820770746, 5769.740361683714, 5528.0, 5579.637323573955, 5609.719514024282, 5670.234518340013, 5677.034904066319, 5601.29983752031, 5611.298587676541, 5621.213030175779, 5640.971795141039, 5654.208410822488, 5478.753456094445, 5479.904019196177, 5532.75102620383, 5584.078627026536, 5585.162225666142, 6242.063690446424, 6246.375362463763, 6325.240971083454, 6347.0, 6360.968195159041, 6378.744850205986, 7012.123484564429, 7024.297570242986, 7026.578405295648, 7036.366726994595, 7077.363037326665, 7114.786556403316, 7124.5012849100585, 7142.14294284684, 7171.497995140342, 6514.837129071764, 6517.9674101630135, 6544.869102617921, 6546.476281897437, 6600.636964966973, 7041.823954401181, 7097.00641910133, 7172.354488096096, 7266.890996635055, 7288.9271107289305, 5078.847634570969, 5581.6930068846605, 6819.420349270367, 6831.077804496329, 6879.58722476653, 7111.502194846415, 7114.64426778659, 7122.786316410516, 7145.249748776408, 7149.785506434815, 5790.507806836382, 6951.443884489228, 6988.091548098715, 7004.859902801915, 7010.263922288086, 6812.523123381415, 6918.79243622682, 6919.065926099912, 6984.02223688684, 6998.650067496605, 5418.674879507243, 5419.485148910847, 5432.348118225759, 5487.2317875497465, 5530.889382212414, 3959.8812035638975, 4878.56092951636, 4908.460069392364, 4919.3604831371695, 4955.380577427822, 6520.315366886503, 6531.673416329165, 6547.443467305237, 6547.574407663486, 6561.114249576246, 7110.57336559808, 7113.288671132948, 7126.2873712628325, 7132.393746531499, 7151.89272160918, 6393.136926514954, 6473.967630161819, 6531.575447595891, 6551.737930482823, 6570.178727659043, 5746.82190151018, 6080.118382834465, 6088.056351265561, 6123.265208174958, 6159.72281247336, 6438.742450301936, 7224.169220539495, 7251.1298644163035, 7254.310840470147, 7290.854182916206, 6948.965255173894, 7074.752383666547, 7082.433405327459, 7104.0, 7117.252688467844, 7162.427005839624, 7199.06412166436, 7209.567425954249, 7209.855802884018, 7213.891791623025, 6756.831079223059, 6868.107146070979, 6903.689333980084, 6951.2353641098425, 6973.12835895513, 6574.980877963923, 7142.821429464305, 7163.641817909188, 7165.390941770008, 7173.820654483679, 6646.4350530205475, 6717.731290748316, 6881.13985751781, 6898.344657257552, 6927.6882540286815, 5354.330708661418, 6527.706253218522, 6651.600903945779, 6738.460923126237, 6861.862762744618, 6043.184170137151, 6686.398224159849, 6776.525643205126, 6788.253292137762, 6807.285235050446, 5964.8807023860145, 6870.793876183722, 6896.379325860697, 6913.688884000333, 6940.514164008471, 6700.430463410665, 6751.324867513209, 6767.93232067673, 6770.763023294358, 6785.287544807724], "value": 6314.947916297763, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [892.1301730812456, 913.8393946790335, 959.91840693541, 974.6539978307705, 976.6581696406258, 38525.61474385255, 39445.81662550121, 39593.01017474557, 39634.207315853666, 39637.0, 39486.01284967877, 39598.80200599, 39766.80116599413, 39796.403053954215, 39927.800360998226, 7626.275503827142, 8040.316573091294, 8046.275835174833, 8047.919520804776, 8052.315553177958, 7886.447948643568, 7911.604419778988, 7948.0, 7959.482633628824, 7981.760547183565, 7882.487638303521, 7882.881756773653, 7891.210878912091, 7896.407769417288, 7903.249191326815, 7849.254320839511, 8484.0, 8710.564471776386, 8728.25809806161, 8728.694495692684, 8539.18877706623, 8552.572371381406, 8565.271951884097, 8602.784930376729, 8604.0, 36860.0, 36939.0, 37052.81473592642, 37125.814370928245, 37141.62858371409, 3614.331348700499, 3622.6558476944647, 3649.1242101895355, 3652.543432070991, 3656.4149736042104, 30380.088597342114, 30414.391712165634, 30485.695143048728, 30509.0, 30527.626256818497, 3062.081375587315, 3171.1120886151916, 3181.1251905726112, 3182.745380369565, 3183.9522407163913, 235357.0, 297328.0, 319533.0, 321775.0, 322712.0, 49578.504214957386, 49745.0, 49782.50217497779, 49875.75062124666, 49883.002339953, 51860.48139518557, 51954.74022629863, 52145.0, 52160.21759673608, 52248.477515225124, 14562.490312839, 14715.484958026418, 14720.558383248519, 14724.779128313085, 14731.263436828116, 9185.311101667401, 11308.378039207759, 11572.900574445413, 12115.939420302842, 12121.454534545966, 11684.824727629093, 12806.0, 13493.122947008413, 13514.121582097132, 13622.795658065137, 5654.321481422174, 5982.73077711504, 6015.368386319461, 6018.307894592091, 6046.123312119718, 907.1291560102376, 985.9014098590227, 1014.8579198912158, 1033.235405799703, 1073.8013467508536, 19636.0, 23354.416139596648, 23490.295291141294, 23506.529869402515, 32865.68537258531, 1143.8455808465917, 1174.5477990973366, 1180.1031216275692, 1182.1665725663386, 1186.4838795123976, 12678.239305641506, 12761.0, 12811.167274127152, 12871.935640321926, 12945.09384343087, 347652.0, 350316.0, 350550.0, 350609.0, 351308.0, 55122.72438637861, 55175.724121379135, 55459.445405546234, 55475.722621387446, 55544.72227638836, 1506.359797086234, 1538.415402147177, 1580.4942418426224, 1585.310389980339, 1588.3011474950983, 17158.65682686314, 21736.130554778265, 22671.546569068196, 23644.8817755917, 24328.878355608107, 39135.21729565336, 39241.018974524726, 39326.01684957899, 39377.0, 39529.407058893565, 307693.0, 307763.0, 308411.0, 308628.0, 309915.0, 31799.841000795772, 32455.513167302037, 32467.35065298728, 36079.639203608676, 36299.45550816687, 43048.78475607727, 44498.3325250128, 44504.55495445004, 44632.55367446284, 45095.0, 28067.696777338697, 28499.857500712365, 29118.0, 29294.952815342574, 29364.0, 26314.21057368282, 27437.31406714848, 30011.0, 30528.847355763966, 31210.68789312078, 37382.2523549522, 37525.43711844379, 37529.81235093807, 37549.68576099823, 37552.624473756005, 27237.45525089527, 29966.0, 30127.548086779156, 32078.679213208503, 32193.35613287768, 22578.548429031656, 23221.187258445872, 24312.784360781538, 24607.0, 24616.876915616023], "value": 17611.031921085832, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.7284289962269, 576.85290250986, 580.4311774461025, 584.5878655547839, 595.8599729063669, 23894.76105238949, 24397.268081957525, 24847.503049939012, 24887.880045397957, 24917.87541062292, 20730.0, 24456.0, 25523.0, 26919.865400673018, 27004.864975675144, 6626.76806311778, 6826.795196144099, 6828.180618325808, 6843.076184715074, 6843.178818541782, 6771.4921380896385, 6827.624480653562, 6828.453723702092, 6832.214295356023, 6846.5892046477375, 6235.469985051276, 6244.0633904914175, 6549.6070235786, 6572.638504882229, 6810.387065164135, 6528.673566321665, 6618.106555614979, 6648.734050637969, 6656.26781054086, 6656.7670131545165, 6164.075388691687, 6185.536084793658, 6191.318954914956, 6206.317305096436, 6207.379262073757, 27389.0, 30486.085417437513, 30932.536011959837, 31059.84470077635, 32153.874614388616, 2179.716636837201, 2218.179273668753, 2242.1816037146486, 2247.4943137793866, 2249.2689875790434, 16588.25352859124, 17176.227069781766, 18356.449306520826, 18871.716924246146, 18961.43115706531, 2243.349428665685, 2248.6851840742147, 2284.463151159476, 2295.185209250725, 2304.9423764405856, 210297.0, 215204.0, 216500.9174954115, 217290.0, 219088.0, 16878.324867005183, 17074.40239591633, 17290.13549322554, 17367.565810854576, 17448.825511744973, 18520.536986575444, 18689.53276168107, 23224.187153449548, 23242.070317187463, 23248.186313479284, 10316.690499285034, 10424.530896109542, 10450.68647940563, 10478.63324783629, 10610.310329828537, 7754.495957762728, 9366.297527685314, 9478.052194780466, 9509.476978766095, 9513.524323783784, 9570.947195808456, 9687.128158465772, 9713.0, 9741.0, 9809.460479673686, 2334.3230463165833, 2723.618693382928, 2747.2719729271757, 2777.4583956128363, 2873.6264285642733, 958.4776296918056, 959.5825815770162, 970.8058388322364, 1006.5475419311684, 1016.8712728870947, 20791.8960405197, 21681.457963551038, 21719.348419547438, 22023.559528809335, 22606.434839129153, 863.7236084453041, 903.7605034665819, 932.9300302477205, 974.4153507895206, 978.5841017567506, 11085.390303533222, 11339.659810205707, 11443.370614616277, 11584.652460426201, 11679.649610511698, 569831.0, 582280.0, 587537.0, 596655.0, 598286.0085699543, 159706.0, 159980.0, 163185.0, 163728.0, 164811.0, 1145.8854114588476, 1213.7997230457052, 1237.7400745843295, 1247.7005518675635, 1253.1666441816271, 19376.12807423641, 19834.00829958525, 20169.39491815248, 20451.693224601346, 20767.169313227303, 70743.64628176826, 71063.0, 72984.0, 73131.0, 74573.0, 90235.0, 90493.64259535981, 94380.0, 95378.09243814946, 95839.52080239553, 12502.187357821713, 12536.05979551519, 12703.93648031754, 12709.0, 12721.363931803558, 18889.866608003565, 19086.33197838069, 19498.512537186685, 19516.707249391555, 19563.926952028338, 14969.850301497281, 15292.61768455753, 15663.295151718428, 15702.528924132293, 15852.60368490797, 12357.382130893599, 13425.932870335977, 14260.786088208477, 14390.352434140279, 14732.852671473149, 14538.709225815635, 15253.694926101638, 15770.369185232463, 16403.015819050896, 16560.923539969863, 12445.688857778267, 12498.875011249771, 12566.0, 12625.81061284063, 12669.049821263257, 15388.538343849703, 15665.216739162768, 15819.0, 15843.970141940737, 15856.44502442409], "value": 12231.549439469309, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1469.5370958148183, 1499.8350181480027, 1537.0316700478697, 1537.723689337849, 1571.4892659885534, 14942.25288735563, 15573.610659733513, 15599.142047187428, 15616.984895981754, 15639.687206255869, 9640.325177237597, 12776.936115319433, 13121.868781312183, 14896.851031489681, 15388.076715397085, 2838.5316422790215, 2890.2774306423394, 2893.6527616686026, 2895.348546577014, 2903.8693258803405, 2835.9291017724618, 2839.687634360219, 2860.957085643717, 2879.7264259895383, 2888.6533615966114, 2840.1905456944774, 2855.414639998806, 2861.3704984903393, 2878.841663708495, 2879.17943386135, 2726.4410795786916, 2820.2244382794793, 2842.7725781937497, 2926.6634337051087, 2932.618759561243, 2306.261996161229, 2916.3000879788697, 2925.7220564046384, 2926.0344086451632, 2928.926776830575, 15197.544073677807, 15244.0, 15299.923500382427, 15310.0, 15317.46388876395, 8153.470024448392, 8399.5380254086, 8468.66125354985, 8487.0, 8528.573571321409, 4858.927116093261, 5145.588352931794, 5282.65662731925, 5343.519083282485, 5377.7311134443125, 1429.371076726237, 1507.3970411835353, 1507.8869084818573, 1526.7709843523448, 1533.0, 23687.052517899265, 23809.0, 23857.642135367983, 24077.0, 24082.63876041843, 7279.67241474135, 7310.4517161213125, 7365.15300740411, 7412.629368531552, 7428.814279643052, 15925.920370398306, 15972.361105555648, 15988.76016859748, 16002.11988340629, 16007.59981000461, 8029.638666260033, 8110.878336824952, 8205.79485512867, 8220.219079187467, 8236.423450358418, 7307.8538429232185, 7483.4013278937555, 7504.962475187698, 7564.735234266775, 7577.128630207487, 5280.419153893069, 5485.643433176911, 5490.615656903979, 5511.807086751945, 5549.805756798492, 1489.3000289863746, 1497.3037537545094, 1500.2423775993054, 1501.7747337899402, 1507.0957425544586, 838.9035260945069, 841.852675781748, 868.6959564152496, 875.5928493250669, 887.5695287785419, 7663.655135518915, 8301.8339633207, 8436.28291595209, 8649.697260595849, 8791.252743516745, 963.8409662405625, 976.9511524423892, 1004.7287232447344, 1006.3861044762689, 1035.1615191694752, 4861.246506791453, 5107.489251074937, 5237.292965449616, 5367.677939323575, 5483.341998960151, 19904.402867913985, 20664.380068597966, 20799.27202547934, 20919.37241882746, 21043.789562104488, 19918.0, 20679.896600516902, 20721.58556828855, 20853.270135545183, 20857.165713371294, 10094.596216151418, 10377.429241391645, 10382.065614094618, 10402.921654675658, 10454.42500662471, 6847.0, 7196.748113815991, 7473.775786726407, 7959.721409750631, 8462.8575142355, 18184.545386365447, 18619.534511636775, 18635.347762828234, 18702.6259474807, 18951.14719837583, 16967.575810604834, 17787.0, 17892.0, 18795.81204187995, 19090.713639295143, 11722.648320550397, 11737.295762254293, 11871.228370156257, 11982.700432489257, 12037.217580857216, 13633.250171240677, 13740.519081832088, 13741.381637826122, 13749.175049497062, 13751.724965500835, 9724.222062234863, 10556.577736890438, 10658.307210031324, 10914.454277286262, 10969.780604388026, 10437.32157409766, 10452.477376131012, 10651.147908167173, 10823.783524329627, 11021.669349919515, 9848.310618256653, 9896.455694936847, 9954.104130628279, 9960.701178964642, 9973.3018688694, 7078.68145933424, 7105.644717764195, 7147.463940204401, 7396.96301518489, 7510.849783004419, 7137.50037497391, 7332.738451280157, 7549.773506794805, 7579.924200757921, 7637.0], "value": 6614.202060058023, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [466.85527486479197, 486.77608300181913, 490.1080034337505, 510.42322175941183, 512.915368964121, 4717.033008233312, 5860.765569377219, 6160.260768707756, 6224.315325314217, 6490.415862572368, 4464.620507256887, 5738.454846789548, 5757.539396848252, 6027.849303767408, 6422.357764223575, 4303.698741088109, 4969.527894849993, 5021.673591216578, 5027.622928280375, 5037.521435463644, 4971.204607262839, 4985.750712464381, 4998.900021999558, 5003.598992282148, 5030.2957585938, 4529.456465224178, 4675.485696573374, 4980.427250866161, 4989.501049895, 5004.249362595603, 4328.372386004027, 4384.320430333271, 4520.434945631796, 4546.636269098464, 4895.21676531755, 4214.620684138442, 4217.114405974749, 4218.388333691628, 4231.97884010581, 4232.87301380956, 6181.629102253879, 6184.443400094013, 6196.132541444156, 6206.844828879269, 6213.00591905293, 5734.996375634248, 5741.9951508486265, 5751.626144300607, 5755.65466072037, 5788.5369170466265, 6871.415929645986, 6882.380585747257, 6884.862302753967, 6895.689693963784, 6909.099997500677, 4079.8980025499304, 4201.411802347674, 4272.4445822042935, 4893.755312234374, 4904.215325547895, 5866.911996320058, 7103.218645948941, 7217.350438460511, 7292.505036467512, 7299.781006569864, 4642.326862604903, 4925.458199598113, 4930.630202734809, 4982.676126051795, 4989.675671081368, 4373.769377617438, 4960.528749768839, 5079.593632509391, 5102.36220472441, 5118.923216151761, 4838.080764654705, 5745.626534275259, 5751.626144300607, 5768.0, 5813.651180929158, 6897.41371983377, 6942.479314051467, 6944.895826562504, 6949.235584085746, 6984.476164287698, 1572.842715728418, 1636.8117666468509, 1740.3908631978707, 1743.8779285449896, 1752.4392194497893, 1260.3005332040623, 1330.6540299522, 1377.8208832851665, 1412.3573773932835, 1412.7951447040123, 5048.3184770056305, 5906.940930590725, 6381.1704478417505, 6422.74309027643, 6448.903266451007, 5795.8551036224435, 7028.156621205386, 7029.26192749754, 7084.752033678797, 7086.433085353159, 5580.274564306615, 6361.61830290184, 6371.840703982345, 6384.648844313608, 6402.775902843471, 3638.872639457606, 3646.4165733482514, 3655.569046584045, 3666.5600127984812, 3672.127215120308, 6732.225794033749, 7223.783286501413, 7224.74713385029, 7231.240719724458, 7242.891356629655, 6500.959846424549, 6807.931920680829, 7104.6447677615915, 7287.599182045038, 7334.523255988343, 7183.748568800067, 7221.855562888818, 7255.2019277878335, 7263.745768898063, 7328.78013659591, 7020.403265722471, 7026.297370262932, 7034.894476582957, 7048.695991241637, 7064.187618423947, 6407.583507072025, 6937.89593156093, 6958.652067396712, 6961.02286864894, 6978.930210697828, 6772.796816095525, 7270.418366530771, 7326.743563975236, 7368.557886526825, 7369.978308893543, 4383.495897971647, 4979.775910083982, 4984.975075124601, 4997.0, 5005.649604527648, 6440.776252512008, 6688.0, 6956.5478243913985, 7107.644617769194, 7134.215236324104, 6580.243272023778, 6765.0, 6822.249552549117, 6835.4189893859575, 6844.384005439534, 6443.387878151568, 6588.505862060269, 6785.491088168308, 6804.931950680628, 6808.795736127924, 6610.636414997221, 6614.867702645824, 6661.267260601233, 6678.398944094861, 6746.69639866217, 5951.0, 6213.813585592439, 6568.080468734476, 6699.899501507579, 6881.799963017221, 5769.653820770767, 5786.627482732059, 6642.667866606554, 6676.799696009128, 6678.632675202911], "value": 5099.895114250982, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [859.6511117792907, 872.8254349130174, 879.2131042716765, 880.8065071827673, 898.0435835834835, 16751.32994680213, 17633.470995870146, 17700.0, 17708.114594270282, 17723.556911077212, 15726.528204153894, 15738.370465181408, 15767.053976761374, 16416.425425110123, 18329.54176145597, 1042.1298215989657, 1064.0423618743155, 1065.7815147894662, 1067.3649178738638, 1067.898549637787, 1046.748780292732, 1050.9894901051007, 1057.5399701129995, 1057.7302787789104, 1057.746140926176, 1105.5190991918541, 1114.9777004459906, 1120.1598800899326, 1120.9607663731772, 1121.2375584602466, 904.2946501728662, 993.2401712689834, 1088.0914436445498, 1110.8500352452436, 1112.9888701113007, 787.2481779900181, 788.9250521200477, 793.670626689923, 793.7698067560401, 796.6176235406979, 18416.631667366713, 18420.263189472404, 18506.722399164024, 18683.90658046701, 18701.064946752744, 17998.460046198634, 18522.07389630513, 18598.999999999865, 18618.720719189223, 18728.250869965184, 15052.096874187475, 15352.232388380537, 15361.846381536154, 15414.922925385414, 15489.92255038729, 3703.1112532992156, 3756.380197267448, 3775.546934367866, 3779.225258821935, 3793.3740932746064, 120175.99999999913, 137803.9086286801, 157954.31251281174, 161289.0, 163098.0, 14415.78376324356, 14525.854741452451, 14559.85440145585, 14706.926465367605, 14843.03520271179, 25360.492790144464, 25819.870900645376, 27215.319617009733, 27557.071005029855, 28097.71902280992, 6939.23668396476, 8268.503889766633, 8295.8340833183, 8336.541490218096, 8354.707585234488, 4051.716379853382, 4077.673786097105, 4096.9795151024055, 4127.628513433805, 4131.111810960658, 4176.937345939813, 4913.87715307113, 5089.898202035939, 5117.155669314521, 5132.538071573502, 1101.130107215307, 1124.3478782306242, 1132.105636547135, 1135.0465608888574, 1145.1869172887177, 658.2627457247913, 669.5915491550151, 669.6183175589867, 686.9798349451061, 693.774523279936, 10270.794584108276, 11682.890125438069, 12451.750964980649, 12794.872051279553, 12991.220526768235, 603.6287683074877, 628.4595248086641, 655.4657953767664, 666.5733930284622, 673.515069150211, 5264.973330200651, 5461.371942226615, 5586.134149206799, 5709.257796486513, 5829.271341082364, 120459.0, 148517.0, 155009.2249538745, 156221.2188939048, 158227.0, 133524.0, 136703.31648341694, 137129.0, 137148.0, 138011.0, 80909.0, 86955.0, 95760.52119739357, 111550.44224778986, 114812.8518714819, 9095.090490951117, 12284.262944223376, 12339.814902776645, 12432.12975091735, 12618.621441356774, 51804.740976294874, 52862.0, 53337.19994200168, 53392.0, 53653.463465364846, 71283.84601215759, 71601.64199178971, 74984.62507687426, 75747.62126189334, 76189.6190519066, 16665.750013750046, 17086.48740537786, 17380.957142571486, 17634.823651763832, 17666.02836843986, 18933.526661833563, 19079.869336458396, 19153.52116197106, 19166.329178478685, 19257.614847703248, 14473.421063157777, 17354.5661358462, 17362.91318543399, 17503.94976301426, 17800.931944083397, 16745.33018679288, 17234.052127133127, 17866.642667146843, 17890.642187156445, 18112.637747244717, 17076.743848842525, 17179.570510737332, 17319.220635071702, 17494.125293735517, 17727.0, 12002.939985300018, 12052.638420847388, 12173.573924912585, 12843.165194262343, 13189.604311870658, 13127.606171814861, 13525.59423217305, 13580.524681636096, 13729.588112356645, 13796.724065518834], "value": 9155.516152540495, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1529.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 112.0, 109.0, 114.0, 147.0, 169.0, 166.0, 114.0, 116.0, 115.0], "value": 126.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 112.0, 139.0, 143.0, 109.0, 133.0, 143.0, 188.0, 176.0, 95.0], "value": 138.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 220.0, 236.0, 234.0, 203.0, 207.0, 200.0, 199.0, 196.0, 200.0], "value": 209.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 86.0, 73.0, 138.0, 61.0, 58.0, 67.0, 63.0, 111.0, 58.0], "value": 78.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 105.0, 147.0, 100.0, 73.0, 80.0, 112.0, 69.0, 71.0, 110.0], "value": 94.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 92.0, 108.0, 101.0, 92.0, 91.0, 90.0, 90.0, 90.0], "value": 93.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 97.0, 98.0, 97.0, 97.0, 99.0, 98.0, 98.0, 99.0, 97.0], "value": 97.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 92.0, 92.0, 116.0, 146.0, 90.0, 90.0, 94.0, 91.0], "value": 99.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 51.0, 49.0, 53.0, 69.0, 44.0, 47.0, 52.0, 54.0], "value": 52.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 61.0, 53.0, 60.0, 54.0, 53.0, 52.0, 52.0], "value": 54.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 96.0, 95.0, 104.0, 101.0, 69.0, 91.0, 71.0, 94.0], "value": 90.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 143.0, 158.0, 134.0, 132.0, 135.0, 140.0, 140.0, 137.0, 159.0], "value": 141.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 183.0, 187.0, 179.0, 187.0, 185.0, 180.0, 179.0, 168.0], "value": 181.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 75.0, 98.0, 89.0, 82.0, 61.0, 64.0, 66.0, 66.0, 62.0], "value": 71.9, "unit": "score"}]}]} 12:29:08 INFO - Return code: 0 12:29:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:29:08 INFO - # TBPL SUCCESS # 12:29:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:29:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:29:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:29:08 INFO - 2017-06-21 12:29:08.856 screenresolution[1955:15573] starting screenresolution argv=screenresolution get 12:29:08 INFO - 2017-06-21 12:29:08.858 screenresolution[1955:15573] Display 0: 1600x1200x32@60 12:29:08 INFO - 2017-06-21 12:29:08.864 screenresolution[1956:15576] starting screenresolution argv=screenresolution list 12:29:08 INFO - Available Modes on Display 0 12:29:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:29:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:29:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:29:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:29:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:29:09 INFO - Intel Iris: 12:29:09 INFO - Chipset Model: Intel Iris 12:29:09 INFO - Type: GPU 12:29:09 INFO - Bus: Built-In 12:29:09 INFO - VRAM (Dynamic, Max): 1536 MB 12:29:09 INFO - Vendor: Intel (0x8086) 12:29:09 INFO - Device ID: 0x0a2e 12:29:09 INFO - Revision ID: 0x0009 12:29:09 INFO - Displays: 12:29:09 INFO - Display: 12:29:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:29:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:29:09 INFO - Display Serial Number: 1600x1200 60 12:29:09 INFO - Main Display: Yes 12:29:09 INFO - Mirror: Off 12:29:09 INFO - Online: Yes 12:29:09 INFO - Rotation: Supported 12:29:09 INFO - Return code: 0 12:29:09 INFO - Running post-action listener: _resource_record_post_action 12:29:09 INFO - [mozharness: 2017-06-21 19:29:09.099753Z] Finished run-tests step (success) 12:29:09 INFO - Running post-run listener: _resource_record_post_run 12:29:09 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 1277952; Write bytes: 568879104; Read time: 16; Write time: 1811 12:29:09 INFO - TinderboxPrint: CPU usage
54.1% 12:29:09 INFO - TinderboxPrint: I/O read bytes / time
1,277,952 / 16 12:29:09 INFO - TinderboxPrint: I/O write bytes / time
568,879,104 / 1,811 12:29:09 INFO - TinderboxPrint: CPU idle
2,206.3 (45.9%) 12:29:09 INFO - TinderboxPrint: CPU system
965.1 (20.1%) 12:29:09 INFO - TinderboxPrint: CPU user
1,638.2 (34.1%) 12:29:09 INFO - TinderboxPrint: Swap in / out
255,913,984 / 0 12:29:09 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 238957056; Write bytes: 236761088; Read time: 17625; Write time: 433 12:29:09 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:29:09 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 1277952; Write bytes: 313653248; Read time: 16; Write time: 1256 12:29:09 INFO - Running post-run listener: _upload_blobber_files 12:29:09 INFO - Blob upload gear active. 12:29:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:29:09 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 12:29:09 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:29:09 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:29:09 INFO - (blobuploader) - INFO - Open directory for files ... 12:29:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:29:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:29:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:11 INFO - (blobuploader) - INFO - Done attempting. 12:29:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:29:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:29:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:12 INFO - (blobuploader) - INFO - Done attempting. 12:29:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:29:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:29:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:13 INFO - (blobuploader) - INFO - Done attempting. 12:29:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:29:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:29:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:15 INFO - (blobuploader) - INFO - Done attempting. 12:29:15 INFO - (blobuploader) - INFO - Iteration through files over. 12:29:15 INFO - Return code: 0 12:29:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:29:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:29:15 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/b2b2a6822d4763bbc41f909823a28ab5f229b5954f99f456451a7d6caecaac789376131cf924f60a8eb92477fe26959c6c4742bd1a9d951c6001b5b723da88b6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5a2623621ef0ec639603da6d7af125ea83fe36f579e008c3058df4ab56f834ad58f6d6fcd9b9e09fb3c371ece1a41a8fcb15f6163b00c371e80e8842b5af9937", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ffdc9cd51c072d35633c13cf070f8ca617c4398873d113715ac9181f77fad7bcb8f32513124c1dcdf8681c3b686037b309664f5c1411d0c025f8b30feaa8a447", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/039cc429215105e1fca31144abbd44aaaa4f5f3a7442973b264c570681bca78eae29292e9dc3d415691199d614d0c7b26e047ec0abc3ab9c939a4becbc2aefd6"} 12:29:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:29:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:29:15 INFO - Contents: 12:29:15 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/b2b2a6822d4763bbc41f909823a28ab5f229b5954f99f456451a7d6caecaac789376131cf924f60a8eb92477fe26959c6c4742bd1a9d951c6001b5b723da88b6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5a2623621ef0ec639603da6d7af125ea83fe36f579e008c3058df4ab56f834ad58f6d6fcd9b9e09fb3c371ece1a41a8fcb15f6163b00c371e80e8842b5af9937", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ffdc9cd51c072d35633c13cf070f8ca617c4398873d113715ac9181f77fad7bcb8f32513124c1dcdf8681c3b686037b309664f5c1411d0c025f8b30feaa8a447", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/039cc429215105e1fca31144abbd44aaaa4f5f3a7442973b264c570681bca78eae29292e9dc3d415691199d614d0c7b26e047ec0abc3ab9c939a4becbc2aefd6"} 12:29:15 INFO - Running post-run listener: copy_logs_to_upload_dir 12:29:15 INFO - Copying logs to upload dir... 12:29:15 INFO - mkdir: /builds/slave/test/build/upload/logs 12:29:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.191324 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-06-21 12:29:15.189216) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-21 12:29:15.194132) ========= 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.O0oRF6k9Gj/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.CjaUPaQNeT/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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/Pine/sha512/b2b2a6822d4763bbc41f909823a28ab5f229b5954f99f456451a7d6caecaac789376131cf924f60a8eb92477fe26959c6c4742bd1a9d951c6001b5b723da88b6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5a2623621ef0ec639603da6d7af125ea83fe36f579e008c3058df4ab56f834ad58f6d6fcd9b9e09fb3c371ece1a41a8fcb15f6163b00c371e80e8842b5af9937", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ffdc9cd51c072d35633c13cf070f8ca617c4398873d113715ac9181f77fad7bcb8f32513124c1dcdf8681c3b686037b309664f5c1411d0c025f8b30feaa8a447", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/039cc429215105e1fca31144abbd44aaaa4f5f3a7442973b264c570681bca78eae29292e9dc3d415691199d614d0c7b26e047ec0abc3ab9c939a4becbc2aefd6"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011361 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/b2b2a6822d4763bbc41f909823a28ab5f229b5954f99f456451a7d6caecaac789376131cf924f60a8eb92477fe26959c6c4742bd1a9d951c6001b5b723da88b6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5a2623621ef0ec639603da6d7af125ea83fe36f579e008c3058df4ab56f834ad58f6d6fcd9b9e09fb3c371ece1a41a8fcb15f6163b00c371e80e8842b5af9937", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ffdc9cd51c072d35633c13cf070f8ca617c4398873d113715ac9181f77fad7bcb8f32513124c1dcdf8681c3b686037b309664f5c1411d0c025f8b30feaa8a447", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/039cc429215105e1fca31144abbd44aaaa4f5f3a7442973b264c570681bca78eae29292e9dc3d415691199d614d0c7b26e047ec0abc3ab9c939a4becbc2aefd6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-21 12:29:15.226103) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 12:29:15.226424) ========= 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.O0oRF6k9Gj/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.CjaUPaQNeT/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.005424 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 12:29:15.274378) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-21 12:29:15.274762) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-21 12:29:15.275012) ========= ========= Total master_lag: 0.17 =========