builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0021 starttime: 1498069309.45 results: success (0) buildid: 20170621111549 builduid: 2ad414d771ef407f8852b9add51654b4 revision: 4f227bec7585a7e4487d7da44c5cc7edd2ad5654 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-21 11:21:49.452869) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-21 11:21:49.453465) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-21 11:21:49.453934) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-21 11:21:49.477851) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 11:21:49.478381) ========= 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.sdILS5Oz7v/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.96pentjAnU/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 11:21:49-- 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% 64.5M=0s 2017-06-21 11:21:49 (64.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.133715 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 11:21:49.634314) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 11:21:49.634626) ========= 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.sdILS5Oz7v/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.96pentjAnU/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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.019661 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 11:21:49.684492) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-21 11:21:49.684954) ========= 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.sdILS5Oz7v/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.96pentjAnU/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 11:21:49,756 truncating revision to first 12 chars 2017-06-21 11:21:49,756 Setting DEBUG logging. 2017-06-21 11:21:49,756 attempt 1/10 2017-06-21 11:21:49,756 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 11:21:50,713 unpacking tar archive at: pine-4f227bec7585/testing/mozharness/ program finished with exit code 0 elapsedTime=1.215025 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-21 11:21:50.923209) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-21 11:21:50.923547) ========= script_repo_revision: 4f227bec7585a7e4487d7da44c5cc7edd2ad5654 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-21 11:21:50.923929) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-21 11:21:50.924193) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-21 11:21:50.942224) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-06-21 11:21:50.942501) ========= /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.sdILS5Oz7v/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.96pentjAnU/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 11:21:51 INFO - MultiFileLogger online at 20170621 11:21:51 in /builds/slave/test 11:21:51 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 11:21:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:21:51 INFO - {'append_to_log': False, 11:21:51 INFO - 'base_work_dir': '/builds/slave/test', 11:21:51 INFO - 'blob_upload_branch': 'Pine', 11:21:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:21:51 INFO - 'branch': 'Pine', 11:21:51 INFO - 'buildbot_json_path': 'buildprops.json', 11:21:51 INFO - 'config_files': ('talos/mac_config.py',), 11:21:51 INFO - 'default_actions': ('clobber', 11:21:51 INFO - 'read-buildbot-config', 11:21:51 INFO - 'download-and-extract', 11:21:51 INFO - 'populate-webroot', 11:21:51 INFO - 'create-virtualenv', 11:21:51 INFO - 'install', 11:21:51 INFO - 'setup-mitmproxy', 11:21:51 INFO - 'run-tests'), 11:21:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:21:51 INFO - 'download_minidump_stackwalk': True, 11:21:51 INFO - 'download_symbols': 'ondemand', 11:21:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:21:51 INFO - 'tooltool.py': '/tools/tooltool.py', 11:21:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:21:51 INFO - '/tools/misc-python/virtualenv.py')}, 11:21:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:21:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:21:51 INFO - 'gecko_profile': False, 11:21:51 INFO - 'gecko_profile_interval': 0, 11:21:51 INFO - 'installer_path': 'installer.exe', 11:21:51 INFO - 'log_level': 'info', 11:21:51 INFO - 'log_name': 'talos', 11:21:51 INFO - 'log_to_console': True, 11:21:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:21:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:21:51 INFO - 'opt_config_files': (), 11:21:51 INFO - 'pip_index': False, 11:21:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:21:51 INFO - 'cmd': ('bash', 11:21:51 INFO - '-c', 11:21:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:21:51 INFO - 'enabled': True, 11:21:51 INFO - 'halt_on_failure': False, 11:21:51 INFO - 'name': 'check_screen_resolution'},), 11:21:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:21:51 INFO - 'cmd': ('bash', 11:21:51 INFO - '-c', 11:21:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:21:51 INFO - 'enabled': True, 11:21:51 INFO - 'halt_on_failure': False, 11:21:51 INFO - 'name': 'check_screen_resolution'},), 11:21:51 INFO - 'run_cmd_checks_enabled': True, 11:21:51 INFO - 'suite': 'dromaeojs-e10s', 11:21:51 INFO - 'system_bits': '32', 11:21:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:21:51 INFO - 'title': 't-yosemite-r7-0021', 11:21:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:21:51 INFO - 'use_talos_json': True, 11:21:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:21:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:21:51 INFO - 'work_dir': 'build'} 11:21:51 INFO - [mozharness: 2017-06-21 18:21:51.138617Z] Running clobber step. 11:21:51 INFO - Running pre-action listener: _resource_record_pre_action 11:21:51 INFO - Running main action method: clobber 11:21:51 INFO - rmtree: /builds/slave/test/build 11:21:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:21:52 INFO - Running post-action listener: _resource_record_post_action 11:21:52 INFO - [mozharness: 2017-06-21 18:21:52.731423Z] Finished clobber step (success) 11:21:52 INFO - [mozharness: 2017-06-21 18:21:52.731665Z] Running read-buildbot-config step. 11:21:52 INFO - Running pre-action listener: _resource_record_pre_action 11:21:52 INFO - Running main action method: read_buildbot_config 11:21:52 INFO - Using buildbot properties: 11:21:52 INFO - { 11:21:52 INFO - "project": "", 11:21:52 INFO - "product": "firefox", 11:21:52 INFO - "script_repo_revision": "default", 11:21:52 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 11:21:52 INFO - "repository": "", 11:21:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 11:21:52 INFO - "buildid": "20170621111549", 11:21:52 INFO - "stage_platform": "macosx64", 11:21:52 INFO - "basedir": "/builds/slave/test", 11:21:52 INFO - "buildnumber": 0, 11:21:52 INFO - "platform": "yosemite_r7", 11:21:52 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 11:21:52 INFO - "slavebuilddir": "test", 11:21:52 INFO - "branch": "pine", 11:21:52 INFO - "revision": "4f227bec7585a7e4487d7da44c5cc7edd2ad5654", 11:21:52 INFO - "slavename": "t-yosemite-r7-0021", 11:21:52 INFO - "builduid": "2ad414d771ef407f8852b9add51654b4", 11:21:52 INFO - "repo_path": "projects/pine" 11:21:52 INFO - } 11:21:52 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.dmg. 11:21:52 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. 11:21:52 INFO - Running post-action listener: _resource_record_post_action 11:21:52 INFO - [mozharness: 2017-06-21 18:21:52.737169Z] Finished read-buildbot-config step (success) 11:21:52 INFO - [mozharness: 2017-06-21 18:21:52.737386Z] Running download-and-extract step. 11:21:52 INFO - Running pre-action listener: _resource_record_pre_action 11:21:52 INFO - Running main action method: download_and_extract 11:21:52 INFO - mkdir: /builds/slave/test/build/tests 11:21:52 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')]} 11:21:52 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.test_packages.json 11:21:52 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 11:21:52 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 11:21:53 INFO - Downloaded 1328 bytes. 11:21:53 INFO - Reading from file /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 11:21:53 INFO - Using the following test package requirements: 11:21:53 INFO - {u'awsy': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 11:21:53 INFO - u'firefox-56.0a1.en-US.mac.awsy.tests.zip'], 11:21:53 INFO - u'common': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 11:21:53 INFO - u'cppunittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 11:21:53 INFO - u'firefox-56.0a1.en-US.mac.cppunittest.tests.zip'], 11:21:53 INFO - u'gtest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 11:21:53 INFO - u'firefox-56.0a1.en-US.mac.gtest.tests.zip'], 11:21:53 INFO - u'jittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 11:21:53 INFO - u'jsshell-mac.zip'], 11:21:53 INFO - u'mochitest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 11:21:53 INFO - u'firefox-56.0a1.en-US.mac.mochitest.tests.zip'], 11:21:53 INFO - u'mozbase': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 11:21:53 INFO - u'reftest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 11:21:53 INFO - u'firefox-56.0a1.en-US.mac.reftest.tests.zip'], 11:21:53 INFO - u'talos': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 11:21:53 INFO - u'firefox-56.0a1.en-US.mac.talos.tests.zip'], 11:21:53 INFO - u'web-platform': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 11:21:53 INFO - u'firefox-56.0a1.en-US.mac.web-platform.tests.tar.gz'], 11:21:53 INFO - u'xpcshell': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 11:21:53 INFO - u'firefox-56.0a1.en-US.mac.xpcshell.tests.zip']} 11:21:53 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:21:53 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 11:21:53 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 11:21:53 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.common.tests.zip into memory 11:21:54 INFO - Content-Length response header: 59596040 11:21:54 INFO - Bytes received: 59596040 11:22:12 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 11:22:12 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 11:22:12 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 11:22:12 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.common.tests.zip into memory 11:22:14 INFO - Content-Length response header: 59596040 11:22:14 INFO - Bytes received: 59596040 11:22:31 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 11:22:31 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 11:22:31 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.talos.tests.zip into memory 11:22:31 INFO - Content-Length response header: 14457085 11:22:31 INFO - Bytes received: 14457085 11:22:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:22:32 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.dmg 11:22:32 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 11:22:32 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 11:22:33 INFO - Downloaded 69703961 bytes. 11:22:33 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 11:22:33 INFO - mkdir: /builds/slave/test/properties 11:22:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:22:33 INFO - Writing to file /builds/slave/test/properties/build_url 11:22:33 INFO - Contents: 11:22:33 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.dmg 11:22:33 INFO - Running post-action listener: _resource_record_post_action 11:22:33 INFO - Running post-action listener: set_extra_try_arguments 11:22:33 INFO - [mozharness: 2017-06-21 18:22:33.938545Z] Finished download-and-extract step (success) 11:22:33 INFO - [mozharness: 2017-06-21 18:22:33.938674Z] Running populate-webroot step. 11:22:33 INFO - Running pre-action listener: _resource_record_pre_action 11:22:33 INFO - Running main action method: populate_webroot 11:22:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:22:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:22:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:22:33 INFO - '--tppagecycles', 11:22:33 INFO - '7'], 11:22:33 INFO - 'tests': ['tsvgm']}}, 11:22:33 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:22:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:22:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:22:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:22:33 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:22:33 INFO - 'talos_options': ['--disable-e10s'], 11:22:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:22:33 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:22:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:22:33 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:22:33 INFO - 'talos_options': ['--disable-e10s'], 11:22:33 INFO - 'tests': ['damp', 'tps']}, 11:22:33 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:22:33 INFO - 'tests': ['damp', 'tps']}, 11:22:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:22:33 INFO - 'tests': ['dromaeo_dom']}, 11:22:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:22:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:22:33 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 11:22:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:22:33 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:22:33 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:22:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:22:33 INFO - 'tests': ['a11yr', 11:22:33 INFO - 'ts_paint', 11:22:33 INFO - 'tpaint', 11:22:33 INFO - 'sessionrestore', 11:22:33 INFO - 'sessionrestore_no_auto_restore', 11:22:33 INFO - 'tabpaint']}, 11:22:33 INFO - 'other-e10s': {'tests': ['a11yr', 11:22:33 INFO - 'ts_paint', 11:22:33 INFO - 'tpaint', 11:22:33 INFO - 'sessionrestore', 11:22:33 INFO - 'sessionrestore_no_auto_restore', 11:22:33 INFO - 'tabpaint']}, 11:22:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:22:33 INFO - 'ts_paint', 11:22:33 INFO - 'tpaint', 11:22:33 INFO - 'sessionrestore', 11:22:33 INFO - 'sessionrestore_no_auto_restore', 11:22:33 INFO - 'tabpaint']}, 11:22:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:22:33 INFO - 'ts_paint', 11:22:33 INFO - 'tpaint', 11:22:33 INFO - 'sessionrestore', 11:22:33 INFO - 'sessionrestore_no_auto_restore', 11:22:33 INFO - 'tabpaint']}, 11:22:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:22:33 INFO - 'tests': ['a11yr', 11:22:33 INFO - 'ts_paint', 11:22:33 INFO - 'tpaint', 11:22:33 INFO - 'sessionrestore', 11:22:33 INFO - 'sessionrestore_no_auto_restore', 11:22:33 INFO - 'tabpaint']}, 11:22:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:22:33 INFO - 'tests': ['a11yr', 11:22:33 INFO - 'ts_paint', 11:22:33 INFO - 'tpaint', 11:22:33 INFO - 'sessionrestore', 11:22:33 INFO - 'sessionrestore_no_auto_restore', 11:22:33 INFO - 'tabpaint']}, 11:22:33 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 11:22:33 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 11:22:33 INFO - 'bloom_basic_ref']}, 11:22:33 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:22:33 INFO - 'talos_options': ['--mitmproxy', 11:22:33 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:22:33 INFO - '--firstNonBlankPaint'], 11:22:33 INFO - 'tests': ['quantum_pageload_google', 11:22:33 INFO - 'quantum_pageload_youtube', 11:22:33 INFO - 'quantum_pageload_amazon', 11:22:33 INFO - 'quantum_pageload_facebook']}, 11:22:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:22:33 INFO - 'tests': ['tsvgx', 11:22:33 INFO - 'tsvgr_opacity', 11:22:33 INFO - 'tart', 11:22:33 INFO - 'tscrollx', 11:22:33 INFO - 'cart', 11:22:33 INFO - 'tsvg_static']}, 11:22:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:22:33 INFO - 'tsvgr_opacity', 11:22:33 INFO - 'tart', 11:22:33 INFO - 'tscrollx', 11:22:33 INFO - 'cart', 11:22:33 INFO - 'tsvg_static']}, 11:22:33 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:22:33 INFO - 'talos_options': ['--disable-e10s'], 11:22:33 INFO - 'tests': ['tp5o']}, 11:22:33 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:22:33 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:22:33 INFO - 'talos_options': ['--disable-e10s', 11:22:33 INFO - '--xperf_path', 11:22:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:22:33 INFO - 'tests': ['tp5n']}, 11:22:33 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:22:33 INFO - 'talos_options': ['--xperf_path', 11:22:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:22:33 INFO - 'tests': ['tp5n']}}, 11:22:33 INFO - 'talos.zip': {'path': '', 11:22:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:22:33 INFO - Running post-action listener: _resource_record_post_action 11:22:33 INFO - [mozharness: 2017-06-21 18:22:33.959356Z] Finished populate-webroot step (success) 11:22:33 INFO - [mozharness: 2017-06-21 18:22:33.959473Z] Running create-virtualenv step. 11:22:33 INFO - Running pre-action listener: _resource_record_pre_action 11:22:33 INFO - Running main action method: create_virtualenv 11:22:33 INFO - Creating virtualenv /builds/slave/test/build/venv 11:22:33 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 11:22:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:22:33 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:22:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:22:34 INFO - Using real prefix '/tools/python27' 11:22:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:22:34 INFO - Installing distribute.............................................................................................................................................................................................done. 11:22:37 INFO - Installing pip.................done. 11:22:37 INFO - Return code: 0 11:22:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:22:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:22:37 INFO - Reading from file tmpfile_stdout 11:22:37 INFO - Output received: 11:22:37 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) 11:22:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:22:37 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')]} 11:22:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:22:37 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')]} 11:22:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:22:37 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 0x10dfabc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfaf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf92d637a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd01d30>, '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 0x10dccbcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dcf2be8>, '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.sdILS5Oz7v/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.96pentjAnU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 11:22:37 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 11:22:37 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 11:22:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sdILS5Oz7v/Render', 11:22:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:22:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:22:37 INFO - 'HOME': '/Users/cltbld', 11:22:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:22:37 INFO - 'LOGNAME': 'cltbld', 11:22:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:22:37 INFO - 'MOZ_NO_REMOTE': '1', 11:22:37 INFO - 'NO_EM_RESTART': '1', 11:22:37 INFO - 'PAGER': '/bin/cat', 11:22:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:22:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:22:37 INFO - 'PWD': '/builds/slave/test', 11:22:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:22:37 INFO - 'SHELL': '/bin/bash', 11:22:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.96pentjAnU/Listeners', 11:22:37 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 11:22:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:22:37 INFO - 'USER': 'cltbld', 11:22:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:22:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:22:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:22:37 INFO - 'XPC_FLAGS': '0x0', 11:22:37 INFO - 'XPC_SERVICE_NAME': '0', 11:22:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:22:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:22:38 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 11:22:38 INFO - Cleaning up... 11:22:38 INFO - Return code: 0 11:22:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:22:38 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')]} 11:22:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:22:38 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')]} 11:22:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:22:38 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 0x10dfabc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfaf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf92d637a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd01d30>, '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 0x10dccbcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dcf2be8>, '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.sdILS5Oz7v/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.96pentjAnU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 11:22:38 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 11:22:38 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 11:22:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sdILS5Oz7v/Render', 11:22:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:22:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:22:38 INFO - 'HOME': '/Users/cltbld', 11:22:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:22:38 INFO - 'LOGNAME': 'cltbld', 11:22:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:22:38 INFO - 'MOZ_NO_REMOTE': '1', 11:22:38 INFO - 'NO_EM_RESTART': '1', 11:22:38 INFO - 'PAGER': '/bin/cat', 11:22:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:22:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:22:38 INFO - 'PWD': '/builds/slave/test', 11:22:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:22:38 INFO - 'SHELL': '/bin/bash', 11:22:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.96pentjAnU/Listeners', 11:22:38 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 11:22:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:22:38 INFO - 'USER': 'cltbld', 11:22:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:22:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:22:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:22:38 INFO - 'XPC_FLAGS': '0x0', 11:22:38 INFO - 'XPC_SERVICE_NAME': '0', 11:22:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:22:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:22:38 INFO - Downloading/unpacking psutil>=3.1.1 11:22:38 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 11:22:38 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 11:22:38 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 11:22:38 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 11:22:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:22:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:22:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:22:40 INFO - Installing collected packages: psutil 11:22:40 INFO - Running setup.py install for psutil 11:22:40 INFO - building 'psutil._psutil_osx' extension 11:22:40 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 11:22:40 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 11:22:40 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 11:22:40 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 11:22:40 INFO - building 'psutil._psutil_posix' extension 11:22:40 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 11:22:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:22:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:22:40 INFO - ^ 11:22:40 INFO - 1 warning generated. 11:22:40 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 11:22:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:22:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:22:41 INFO - Successfully installed psutil 11:22:41 INFO - Cleaning up... 11:22:41 INFO - Return code: 0 11:22:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:22: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')]} 11:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:22: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')]} 11:22:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:22: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dfabc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfaf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf92d637a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd01d30>, '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 0x10dccbcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dcf2be8>, '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.sdILS5Oz7v/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.96pentjAnU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 11:22: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:22: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 mozsystemmonitor==0.3 11:22:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sdILS5Oz7v/Render', 11:22:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:22:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:22:41 INFO - 'HOME': '/Users/cltbld', 11:22:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:22:41 INFO - 'LOGNAME': 'cltbld', 11:22:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:22:41 INFO - 'MOZ_NO_REMOTE': '1', 11:22:41 INFO - 'NO_EM_RESTART': '1', 11:22:41 INFO - 'PAGER': '/bin/cat', 11:22:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:22:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:22:41 INFO - 'PWD': '/builds/slave/test', 11:22:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:22:41 INFO - 'SHELL': '/bin/bash', 11:22:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.96pentjAnU/Listeners', 11:22:41 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 11:22:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:22:41 INFO - 'USER': 'cltbld', 11:22:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:22:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:22:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:22:41 INFO - 'XPC_FLAGS': '0x0', 11:22:41 INFO - 'XPC_SERVICE_NAME': '0', 11:22:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:22:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:22:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:22: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 11:22: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 11:22: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 11:22: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 11:22:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:22:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:22:42 INFO - Installing collected packages: mozsystemmonitor 11:22:42 INFO - Running setup.py install for mozsystemmonitor 11:22:43 INFO - Successfully installed mozsystemmonitor 11:22:43 INFO - Cleaning up... 11:22:43 INFO - Return code: 0 11:22:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:22:43 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')]} 11:22:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:22:43 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')]} 11:22:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:22:43 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 0x10dfabc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfaf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf92d637a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd01d30>, '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 0x10dccbcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dcf2be8>, '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.sdILS5Oz7v/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.96pentjAnU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 11:22:43 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 11:22:43 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 11:22:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sdILS5Oz7v/Render', 11:22:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:22:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:22:43 INFO - 'HOME': '/Users/cltbld', 11:22:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:22:43 INFO - 'LOGNAME': 'cltbld', 11:22:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:22:43 INFO - 'MOZ_NO_REMOTE': '1', 11:22:43 INFO - 'NO_EM_RESTART': '1', 11:22:43 INFO - 'PAGER': '/bin/cat', 11:22:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:22:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:22:43 INFO - 'PWD': '/builds/slave/test', 11:22:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:22:43 INFO - 'SHELL': '/bin/bash', 11:22:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.96pentjAnU/Listeners', 11:22:43 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 11:22:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:22:43 INFO - 'USER': 'cltbld', 11:22:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:22:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:22:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:22:43 INFO - 'XPC_FLAGS': '0x0', 11:22:43 INFO - 'XPC_SERVICE_NAME': '0', 11:22:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:22:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:22:43 INFO - Downloading/unpacking jsonschema==2.5.1 11:22:43 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 11:22:43 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 11:22:43 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 11:22:43 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 11:22:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:22:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:22:44 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 11:22:44 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 11:22:44 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 11:22:44 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 11:22:44 INFO - Downloading functools32-3.2.3-2.tar.gz 11:22:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:22:44 INFO - warning: no files found matching '*.txt' 11:22:44 INFO - no previously-included directories found matching 'build' 11:22:44 INFO - no previously-included directories found matching 'dist' 11:22:44 INFO - no previously-included directories found matching '.git*' 11:22:45 INFO - Installing collected packages: jsonschema, functools32 11:22:45 INFO - Running setup.py install for functools32 11:22:45 INFO - warning: no files found matching '*.txt' 11:22:45 INFO - no previously-included directories found matching 'build' 11:22:45 INFO - no previously-included directories found matching 'dist' 11:22:45 INFO - no previously-included directories found matching '.git*' 11:22:45 INFO - Successfully installed jsonschema functools32 11:22:45 INFO - Cleaning up... 11:22:45 INFO - Return code: 0 11:22:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:22:45 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')]} 11:22:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:22:45 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')]} 11:22:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:22:45 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 0x10dfabc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfaf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf92d637a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd01d30>, '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 0x10dccbcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dcf2be8>, '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.sdILS5Oz7v/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.96pentjAnU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 11:22:45 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 11:22:45 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 11:22:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sdILS5Oz7v/Render', 11:22:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:22:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:22:45 INFO - 'HOME': '/Users/cltbld', 11:22:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:22:45 INFO - 'LOGNAME': 'cltbld', 11:22:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:22:45 INFO - 'MOZ_NO_REMOTE': '1', 11:22:45 INFO - 'NO_EM_RESTART': '1', 11:22:45 INFO - 'PAGER': '/bin/cat', 11:22:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:22:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:22:45 INFO - 'PWD': '/builds/slave/test', 11:22:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:22:45 INFO - 'SHELL': '/bin/bash', 11:22:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.96pentjAnU/Listeners', 11:22:45 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 11:22:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:22:45 INFO - 'USER': 'cltbld', 11:22:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:22:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:22:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:22:45 INFO - 'XPC_FLAGS': '0x0', 11:22:45 INFO - 'XPC_SERVICE_NAME': '0', 11:22:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:22:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:22:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:22:45 INFO - Cleaning up... 11:22:45 INFO - Return code: 0 11:22:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:22:45 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')]} 11:22:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:22:45 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')]} 11:22:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:22:45 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 0x10dfabc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfaf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf92d637a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd01d30>, '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 0x10dccbcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dcf2be8>, '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.sdILS5Oz7v/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.96pentjAnU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 11:22:45 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 11:22:45 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 11:22:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sdILS5Oz7v/Render', 11:22:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:22:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:22:45 INFO - 'HOME': '/Users/cltbld', 11:22:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:22:45 INFO - 'LOGNAME': 'cltbld', 11:22:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:22:45 INFO - 'MOZ_NO_REMOTE': '1', 11:22:45 INFO - 'NO_EM_RESTART': '1', 11:22:45 INFO - 'PAGER': '/bin/cat', 11:22:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:22:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:22:45 INFO - 'PWD': '/builds/slave/test', 11:22:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:22:45 INFO - 'SHELL': '/bin/bash', 11:22:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.96pentjAnU/Listeners', 11:22:45 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 11:22:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:22:45 INFO - 'USER': 'cltbld', 11:22:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:22:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:22:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:22:45 INFO - 'XPC_FLAGS': '0x0', 11:22:45 INFO - 'XPC_SERVICE_NAME': '0', 11:22:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:22:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:22:45 INFO - Downloading/unpacking blobuploader==1.2.4 11:22: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 11:22: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 11:22: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 11:22: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 11:22:47 INFO - Downloading blobuploader-1.2.4.tar.gz 11:22:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:22:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:22: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 11:22: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 11:22: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 11:22: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 11:22:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:22:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:22: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 11:22: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 11:22: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 11:22: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 11:22:47 INFO - Downloading docopt-0.6.1.tar.gz 11:22:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:22:48 INFO - Installing collected packages: blobuploader, requests, docopt 11:22:48 INFO - Running setup.py install for blobuploader 11:22:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:22:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:22:48 INFO - Running setup.py install for requests 11:22:48 INFO - Running setup.py install for docopt 11:22:48 INFO - Successfully installed blobuploader requests docopt 11:22:48 INFO - Cleaning up... 11:22:48 INFO - Return code: 0 11:22:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:22:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:22: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')]} 11:22:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:22:49 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 0x10dfabc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfaf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf92d637a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd01d30>, '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 0x10dccbcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dcf2be8>, '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.sdILS5Oz7v/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.96pentjAnU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 11:22:49 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 11:22:49 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 11:22:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sdILS5Oz7v/Render', 11:22:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:22:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:22:49 INFO - 'HOME': '/Users/cltbld', 11:22:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:22:49 INFO - 'LOGNAME': 'cltbld', 11:22:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:22:49 INFO - 'MOZ_NO_REMOTE': '1', 11:22:49 INFO - 'NO_EM_RESTART': '1', 11:22:49 INFO - 'PAGER': '/bin/cat', 11:22:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:22:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:22:49 INFO - 'PWD': '/builds/slave/test', 11:22:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:22:49 INFO - 'SHELL': '/bin/bash', 11:22:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.96pentjAnU/Listeners', 11:22:49 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 11:22:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:22:49 INFO - 'USER': 'cltbld', 11:22:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:22:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:22:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:22:49 INFO - 'XPC_FLAGS': '0x0', 11:22:49 INFO - 'XPC_SERVICE_NAME': '0', 11:22:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:22:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:22:49 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-l4aiOr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:22:50 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-Gdde8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:22:50 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-9qx6Kc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:22:50 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-sFeyFJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:22:50 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-ZDLhXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:22:50 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-0nbIvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:22:50 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-UsXmT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:22:50 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-2EDdw_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:22:50 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-Ef8ng_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:22:50 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-ngF7Qu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:22:50 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-TUV8o9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:22:51 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-l6cCWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:22:51 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-Y65A8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:22:51 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-c1w2N3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:22:51 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-0Vil3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:22:51 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-fxvTxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:22:51 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-FTsP8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:22:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:22:51 INFO - Running setup.py install for manifestparser 11:22:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:22:51 INFO - Running setup.py install for mozcrash 11:22:51 INFO - Running setup.py install for mozdebug 11:22:52 INFO - Running setup.py install for mozdevice 11:22:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:22:52 INFO - Running setup.py install for mozfile 11:22:52 INFO - Running setup.py install for mozhttpd 11:22:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:22:52 INFO - Running setup.py install for mozinfo 11:22:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:22:52 INFO - Running setup.py install for mozInstall 11:22:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:22:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:22:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:22:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:22:52 INFO - Running setup.py install for mozleak 11:22:52 INFO - Running setup.py install for mozlog 11:22:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:22:53 INFO - Running setup.py install for moznetwork 11:22:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:22:53 INFO - Running setup.py install for mozprocess 11:22:53 INFO - Running setup.py install for mozprofile 11:22:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:22:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:22:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:22:53 INFO - Running setup.py install for mozrunner 11:22:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:22:53 INFO - Running setup.py install for mozscreenshot 11:22:53 INFO - Running setup.py install for moztest 11:22:54 INFO - Running setup.py install for mozversion 11:22:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:22:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:22:54 INFO - Cleaning up... 11:22:54 INFO - Return code: 0 11:22:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:22: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')]} 11:22:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:22: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')]} 11:22:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:22:54 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 0x10dfabc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfaf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf92d637a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd01d30>, '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 0x10dccbcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dcf2be8>, '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.sdILS5Oz7v/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.96pentjAnU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 11:22:54 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 11:22:54 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 11:22:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sdILS5Oz7v/Render', 11:22:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:22:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:22:54 INFO - 'HOME': '/Users/cltbld', 11:22:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:22:54 INFO - 'LOGNAME': 'cltbld', 11:22:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:22:54 INFO - 'MOZ_NO_REMOTE': '1', 11:22:54 INFO - 'NO_EM_RESTART': '1', 11:22:54 INFO - 'PAGER': '/bin/cat', 11:22:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:22:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:22:54 INFO - 'PWD': '/builds/slave/test', 11:22:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:22:54 INFO - 'SHELL': '/bin/bash', 11:22:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.96pentjAnU/Listeners', 11:22:54 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 11:22:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:22:54 INFO - 'USER': 'cltbld', 11:22:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:22:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:22:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:22:54 INFO - 'XPC_FLAGS': '0x0', 11:22:54 INFO - 'XPC_SERVICE_NAME': '0', 11:22:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:22:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:22:54 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-1lsLfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:22:54 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)) 11:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:22:54 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-CEMpE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:22:55 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)) 11:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:22:55 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-7biYaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:22:55 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)) 11:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:22:55 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-pOatYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:22:55 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)) 11:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:22:55 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-w1X9cE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:22:55 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)) 11:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:22:55 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-M8OHRJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:22:55 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)) 11:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:22:55 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-hFk7l3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:22:55 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)) 11:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:22:55 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-wmzzEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:22:55 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)) 11:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:22:55 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-CIs8Ca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:22:55 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)) 11:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:22:55 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-HGdrps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:22:55 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)) 11:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:22:55 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-kLnZ5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:22:56 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)) 11:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:22:56 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-LWA19f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:22:56 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)) 11:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:22:56 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-QWUYLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:22:56 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)) 11:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:22:56 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-hQY7ll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:22:56 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)) 11:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:22:56 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-BAi32l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:22:56 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)) 11:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:22:56 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-hIziT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:22:56 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)) 11:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:22:56 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-YGchd8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:22:56 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)) 11:22:56 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)) 11:22:56 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)) 11:22:56 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)) 11:22:56 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)) 11:22:56 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)) 11:22:56 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)) 11:22:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:22:56 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 11:22:56 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 11:22:56 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 11:22:56 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 11:22:58 INFO - Downloading blessings-1.6.tar.gz 11:22:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:22:58 INFO - Installing collected packages: blessings 11:22:58 INFO - Running setup.py install for blessings 11:22:58 INFO - Successfully installed blessings 11:22:58 INFO - Cleaning up... 11:22:58 INFO - Return code: 0 11:22:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:22:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:22:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:22:59 INFO - Reading from file tmpfile_stdout 11:22:59 INFO - Current package versions: 11:22:59 INFO - blessings == 1.6 11:22:59 INFO - blobuploader == 1.2.4 11:22:59 INFO - docopt == 0.6.1 11:22:59 INFO - functools32 == 3.2.3-2 11:22:59 INFO - jsonschema == 2.5.1 11:22:59 INFO - manifestparser == 1.1 11:22:59 INFO - mozInstall == 1.13 11:22:59 INFO - mozcrash == 1.0 11:22:59 INFO - mozdebug == 0.1 11:22:59 INFO - mozdevice == 0.51 11:22:59 INFO - mozfile == 1.2 11:22:59 INFO - mozhttpd == 0.7 11:22:59 INFO - mozinfo == 0.10 11:22:59 INFO - mozleak == 0.1 11:22:59 INFO - mozlog == 3.4 11:22:59 INFO - moznetwork == 0.27 11:22:59 INFO - mozprocess == 0.25 11:22:59 INFO - mozprofile == 0.28 11:22:59 INFO - mozrunner == 6.13 11:22:59 INFO - mozscreenshot == 0.1 11:22:59 INFO - mozsystemmonitor == 0.3 11:22:59 INFO - moztest == 0.8 11:22:59 INFO - mozversion == 1.4 11:22:59 INFO - psutil == 3.1.1 11:22:59 INFO - requests == 1.2.3 11:22:59 INFO - wsgiref == 0.1.2 11:22:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:22: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')]} 11:22:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:22: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')]} 11:22:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:22:59 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 0x10dfabc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfaf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf92d637a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd01d30>, '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 0x10dccbcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dcf2be8>, '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.sdILS5Oz7v/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.96pentjAnU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 11:22:59 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 11:22:59 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 11:22:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sdILS5Oz7v/Render', 11:22:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:22:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:22:59 INFO - 'HOME': '/Users/cltbld', 11:22:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:22:59 INFO - 'LOGNAME': 'cltbld', 11:22:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:22:59 INFO - 'MOZ_NO_REMOTE': '1', 11:22:59 INFO - 'NO_EM_RESTART': '1', 11:22:59 INFO - 'PAGER': '/bin/cat', 11:22:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:22:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:22:59 INFO - 'PWD': '/builds/slave/test', 11:22:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:22:59 INFO - 'SHELL': '/bin/bash', 11:22:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.96pentjAnU/Listeners', 11:22:59 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 11:22:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:22:59 INFO - 'USER': 'cltbld', 11:22:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:22:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:22:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:22:59 INFO - 'XPC_FLAGS': '0x0', 11:22:59 INFO - 'XPC_SERVICE_NAME': '0', 11:22:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:22:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:22:59 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)) 11:22:59 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)) 11:22:59 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)) 11:22:59 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)) 11:22:59 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)) 11:22:59 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)) 11:22:59 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)) 11:22:59 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)) 11:22:59 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)) 11:22:59 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:22:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:22:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:22:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:22:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:23:00 INFO - Downloading marionette_driver-1.4.0.tar.gz 11:23:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 11:23:00 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)) 11:23:00 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)) 11:23:00 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)) 11:23:00 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)) 11:23:00 INFO - Installing collected packages: marionette-driver 11:23:00 INFO - Running setup.py install for marionette-driver 11:23:01 INFO - Successfully installed marionette-driver 11:23:01 INFO - Cleaning up... 11:23:01 INFO - Return code: 0 11:23:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:23:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:23:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:23:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dfabc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfaf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf92d637a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd01d30>, '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 0x10dccbcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dcf2be8>, '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.sdILS5Oz7v/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.96pentjAnU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 11:23:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:23:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:23:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sdILS5Oz7v/Render', 11:23:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:01 INFO - 'HOME': '/Users/cltbld', 11:23:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:01 INFO - 'LOGNAME': 'cltbld', 11:23:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:01 INFO - 'MOZ_NO_REMOTE': '1', 11:23:01 INFO - 'NO_EM_RESTART': '1', 11:23:01 INFO - 'PAGER': '/bin/cat', 11:23:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:01 INFO - 'PWD': '/builds/slave/test', 11:23:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:01 INFO - 'SHELL': '/bin/bash', 11:23:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.96pentjAnU/Listeners', 11:23:01 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 11:23:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:01 INFO - 'USER': 'cltbld', 11:23:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:01 INFO - 'XPC_FLAGS': '0x0', 11:23:01 INFO - 'XPC_SERVICE_NAME': '0', 11:23:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:23:01 INFO - Cleaning up... 11:23:01 INFO - Return code: 0 11:23:01 INFO - Running post-action listener: _resource_record_post_action 11:23:01 INFO - Running post-action listener: _start_resource_monitoring 11:23:01 INFO - Starting resource monitoring. 11:23:01 INFO - [mozharness: 2017-06-21 18:23:01.357112Z] Finished create-virtualenv step (success) 11:23:01 INFO - [mozharness: 2017-06-21 18:23:01.358090Z] Running install step. 11:23:01 INFO - Running pre-action listener: _resource_record_pre_action 11:23:01 INFO - Running main action method: install 11:23:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:23:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:23:01 INFO - Reading from file tmpfile_stdout 11:23:01 INFO - Detecting whether we're running mozinstall >=1.0... 11:23:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:23:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:23:01 INFO - Reading from file tmpfile_stdout 11:23:01 INFO - Output received: 11:23:01 INFO - Usage: mozinstall [options] installer 11:23:01 INFO - Options: 11:23:01 INFO - -h, --help show this help message and exit 11:23:01 INFO - -d DEST, --destination=DEST 11:23:01 INFO - Directory to install application into. [default: 11:23:01 INFO - "/builds/slave/test"] 11:23:01 INFO - --app=APP Application being installed. [default: firefox] 11:23:01 INFO - mkdir: /builds/slave/test/build/application 11:23:01 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'] 11:23:01 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 11:23:23 INFO - Reading from file tmpfile_stdout 11:23:23 INFO - Output received: 11:23:23 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:23:23 INFO - Running post-action listener: _resource_record_post_action 11:23:23 INFO - [mozharness: 2017-06-21 18:23:23.329882Z] Finished install step (success) 11:23:23 INFO - [mozharness: 2017-06-21 18:23:23.330020Z] Running setup-mitmproxy step. 11:23:23 INFO - Running pre-action listener: _resource_record_pre_action 11:23:23 INFO - Running main action method: setup_mitmproxy 11:23:23 INFO - Skipping: mitmproxy is not required 11:23:23 INFO - Running post-action listener: _resource_record_post_action 11:23:23 INFO - [mozharness: 2017-06-21 18:23:23.330703Z] Finished setup-mitmproxy step (success) 11:23:23 INFO - [mozharness: 2017-06-21 18:23:23.330832Z] Running run-tests step. 11:23:23 INFO - Running pre-action listener: _resource_record_pre_action 11:23:23 INFO - Running main action method: run_tests 11:23:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:23:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:23:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:23:23 INFO - 2017-06-21 11:23:23.345 screenresolution[1976:11884] starting screenresolution argv=screenresolution get 11:23:23 INFO - 2017-06-21 11:23:23.348 screenresolution[1976:11884] Display 0: 1600x1200x32@60 11:23:23 INFO - 2017-06-21 11:23:23.354 screenresolution[1977:11886] starting screenresolution argv=screenresolution list 11:23:23 INFO - Available Modes on Display 0 11:23:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:23:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:23:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:23:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:23:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:23:23 INFO - Intel Iris: 11:23:23 INFO - Chipset Model: Intel Iris 11:23:23 INFO - Type: GPU 11:23:23 INFO - Bus: Built-In 11:23:23 INFO - VRAM (Dynamic, Max): 1536 MB 11:23:23 INFO - Vendor: Intel (0x8086) 11:23:23 INFO - Device ID: 0x0a2e 11:23:23 INFO - Revision ID: 0x0009 11:23:23 INFO - Displays: 11:23:23 INFO - Display: 11:23:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:23:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:23:23 INFO - Display Serial Number: 1600x1200 60 11:23:23 INFO - Main Display: Yes 11:23:23 INFO - Mirror: Off 11:23:23 INFO - Online: Yes 11:23:23 INFO - Rotation: Supported 11:23:23 INFO - Return code: 0 11:23:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:23:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:23:23 INFO - Python 2.7.3 11:23:23 INFO - Return code: 0 11:23:23 INFO - grabbing minidump binary from tooltool 11:23:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:23:23 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 0x10dd01d30>, '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 0x10dccbcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dcf2be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:23:23 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 11:23:23 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 11:23:23 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 11:23:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:23:23 INFO - Return code: 0 11:23:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:23:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:23:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:23:23 INFO - ENV: RUST_BACKTRACE is now 1 11:23:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:23:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:23:23 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-0021', '--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 11:23:23 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-0021 --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 11:23:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sdILS5Oz7v/Render', 11:23:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:23 INFO - 'HOME': '/Users/cltbld', 11:23:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:23 INFO - 'LOGNAME': 'cltbld', 11:23:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:23:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:23:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:23 INFO - 'MOZ_NO_REMOTE': '1', 11:23:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:23:23 INFO - 'NO_EM_RESTART': '1', 11:23:23 INFO - 'PAGER': '/bin/cat', 11:23:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:23 INFO - 'PWD': '/builds/slave/test', 11:23:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:23:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:23 INFO - 'RUST_BACKTRACE': '1', 11:23:23 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:23:23 INFO - 'SHELL': '/bin/bash', 11:23:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.96pentjAnU/Listeners', 11:23:23 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 11:23:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:23 INFO - 'USER': 'cltbld', 11:23:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:23 INFO - 'XPC_FLAGS': '0x0', 11:23:23 INFO - 'XPC_SERVICE_NAME': '0', 11:23:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:23 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-0021', '--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 11:23:23 INFO - mozversion application_buildid: 20170620132059 11:23:23 INFO - mozversion application_changeset: 4f227bec7585a7e4487d7da44c5cc7edd2ad5654 11:23:23 INFO - mozversion application_display_name: Nightly 11:23:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:23:23 INFO - mozversion application_name: Firefox 11:23:23 INFO - mozversion application_remotingname: firefox 11:23:23 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 11:23:23 INFO - mozversion application_vendor: Mozilla 11:23:23 INFO - mozversion application_version: 56.0a1 11:23:23 INFO - mozversion platform_buildid: 20170620132059 11:23:23 INFO - mozversion platform_changeset: 4f227bec7585a7e4487d7da44c5cc7edd2ad5654 11:23:23 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 11:23:23 INFO - mozversion platform_version: 56.0a1 11:23:23 INFO - using testdate: 1498069403 11:23:23 INFO - actual date: 1498069403 11:23:23 INFO - starting webserver on 'localhost:51218' 11:23:23 INFO - SUITE-START | Running 2 tests 11:23:23 INFO - TEST-START | dromaeo_css 11:23:23 INFO - operating with platform_type : mac_ 11:23:23 INFO - Initialising browser for dromaeo_css test... 11:23:24 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:51218/getInfo.html -foreground -profile /var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/tmpmuGdrf/profile 11:23:24 INFO - TEST-INFO | started process 1987 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:51218/getInfo.html -foreground) 11:23:28 INFO - TEST-INFO | 1987: exit 0 11:23:28 INFO - Browser initialized. 11:23:28 INFO - Running cycle 1/1 for dromaeo_css test... 11:23:28 INFO - TEST-INFO | started process 1995 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/tmpmuGdrf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:23:30 INFO - PID 1995 | 2017-06-21 11:23:30.578 plugin-container[1996:12280] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 11:23:30 INFO - PID 1995 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:23:30 INFO - PID 1995 | 2017-06-21 11:23:30.578 plugin-container[1996:12280] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b17, name = 'com.apple.CFPasteboardClient' 11:23:30 INFO - PID 1995 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:23:30 INFO - PID 1995 | 2017-06-21 11:23:30.578 plugin-container[1996:12280] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:23:31 INFO - PID 1995 | 2017-06-21 11:23:31.367 plugin-container[1997:12327] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 11:23:31 INFO - PID 1995 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:23:31 INFO - PID 1995 | 2017-06-21 11:23:31.367 plugin-container[1997:12327] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4817, name = 'com.apple.CFPasteboardClient' 11:23:31 INFO - PID 1995 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:23:31 INFO - PID 1995 | 2017-06-21 11:23:31.367 plugin-container[1997:12327] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:23:40 INFO - PID 1995 | RSS: Main: 249356288 11:23:40 INFO - PID 1995 | 11:26:46 INFO - PID 1995 | Cycle 1(1): loaded http://localhost:51218/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51218/tests/dromaeo/cssquery-ext.html) 11:26:48 INFO - PID 1995 | RSS: Main: 265568256 11:26:48 INFO - PID 1995 | 11:29:50 INFO - PID 1995 | Cycle 1(1): loaded http://localhost:51218/tests/dromaeo/cssquery-ext.html (next: http://localhost:51218/tests/dromaeo/cssquery-jquery.html) 11:29:50 INFO - PID 1995 | RSS: Main: 269000704 11:29:50 INFO - PID 1995 | 11:32:52 INFO - PID 1995 | Cycle 1(1): loaded http://localhost:51218/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51218/tests/dromaeo/cssquery-mootools.html) 11:32:52 INFO - PID 1995 | RSS: Main: 271585280 11:32:52 INFO - PID 1995 | 11:35:54 INFO - PID 1995 | Cycle 1(1): loaded http://localhost:51218/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51218/tests/dromaeo/cssquery-prototype.html) 11:35:55 INFO - PID 1995 | RSS: Main: 270270464 11:35:55 INFO - PID 1995 | 11:38:58 INFO - PID 1995 | Cycle 1(1): loaded http://localhost:51218/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51218/tests/dromaeo/cssquery-yui.html) 11:38:58 INFO - PID 1995 | RSS: Main: 272502784 11:38:58 INFO - PID 1995 | 11:42:00 INFO - PID 1995 | Cycle 1(1): loaded http://localhost:51218/tests/dromaeo/cssquery-yui.html (next: http://localhost:51218/tests/dromaeo/cssquery-dojo.html) 11:42:00 INFO - PID 1995 | RSS: Main: 282750976 11:42:00 INFO - PID 1995 | 11:42:00 INFO - PID 1995 | __start_tp_report 11:42:00 INFO - PID 1995 | _x_x_mozilla_page_load 11:42:00 INFO - PID 1995 | _x_x_mozilla_page_load_details 11:42:00 INFO - PID 1995 | |i|pagename|runs| 11:42:00 INFO - PID 1995 | |0;dojo.html;3420.880269190578;3581.64183581642;3651.6165802590726;3659.2864391443673;3668.3030224257404;5838.941610583893;5881.382454842244;6193.938060619393;6430;6445.097686323918;6387.90418143727;6401.0398440233985;6403.487720982322;6406.455451286634;6421.422072013518;5574.052411090104;5716.22830917827;5719.227904232917;5733.827985160452;5826.708664566776;4825.672939941509;5867.589268751189;5882.264716910387;5884.852878678024;5899.144624029514;5790.536757059467;5837.737301821407;5853.385394533577;5863.706814659271;5873.911891321633;5601.887962240773;5604.943950560524;5737.053386191274;5771.653700777924;5797.971010144965;5570.944290557083;5598.328200615912;5598.356189038272;5615.522680572156;5638.182463542805;5691.459311365414;6446.871062578769;6495.9350406495805;6500.349965003509;6517.446017088553;7156.499045066847;7179.928200717978;7184.1019872515935;7190.424766018706;7193.89209161863;6304.432601065881;6454.93545064548;6617.569857959217;7229;7323.450741194485;6530.477561795045;6584.374484423972;6726.39462448382;6749.920012797928;6756.155480564929;6776.627285499247;7243.239459856744;7300.6349682515665;7323.267673232741;7348.412127029825;5728.255326807488;6805.761798337035;6995.300469952964;7005.509614326948;7053.929460705327;7045.577265363992;7060.082189315459;7072.398846098036;7073.61095139762;7074.75238366665;5947.6431414115295;7005.719771209197;7046.6828992695455;7082.433405327562;7119.857602848017;7066.258042905421;7083.7520686775715;7098.503104782719;7107;7116.359527642537;5542.196381524656;5543.1408131739645;5544.251526043932;5566.526845218121;5579;4055.97972010144;4341.934870976938;4849.2968519564465;4870.001649661811;4875.317455556225;6297.874042519124;6627.072209890619;6647.567908085959;6666.200055993215;6669.066330713704;7309.744158954411;7320.304571065741;7323.853522929511;7328.560286382727;7338.156112047183;6713.765018224436;6714.9664251679405;6723.193216814014;6727.629980351127;6733.46132309414;6114.174586430864;6245.094461303085;6264.404881536338;6280.214973128359;6285.685715714196;7298.4891057625455;7303.050603421733;7315.8536829264185;7331.04696389466;7380.96309518449;7152.213256541881;7176.7129314826825;7199.06412166415;7260.382867456326;7271.309225623558;7409.925900740924;7410.592417416879;7420.740274090381;7423.294786995227;7477.252274772479;6976.267491913276;7115.644217789193;7143.535670181422;7147.249538798351;7157.320054594805;6942.895856562054;7069.858602828018;7221.819454513679;7234.819129521594;7257.165425975869;6868.347506986828;6955.8956615649795;6969.1985421677155;6994.985727069648;7039.1553013638695;6974.232834388315;6991.335823096798;7025.613591252531;7036.824079398056;7042.7887163385185;6779.118714567272;6901.965490172517;6913.896291555731;6920.446364290945;6925.099737034355;6778.491613129134;6883.242843287335;6887.6556172192195;7012.544184627983;7049.118860142482;5992.833681695959;6269.934111201198;6680.766173183915;6791.72833086671;6802.863942721019 11:42:00 INFO - PID 1995 | |1;ext.html;950.4439902656948;965.0446058402183;978.2711879649662;983.7358993693101;1005.1707341443312;41455.79272103639;42473.78763106188;42528.78735606325;42699.14601707964;42805.35791963119;42312.36531452023;42474;42644.360334595;42688.146237075314;42729.78635106828;8116.91883081169;8221.136780638037;8260.504369737804;8264.83470330593;8282.461639993411;8075.838483230332;8162.632681529316;8196.54918979456;8196.67213311467;8198.221168988966;6557.377049180321;7548.5848278344665;8132.959335203315;8154.75535733926;8204.712835050774;6924.23833378328;8136.633851476698;8145.633446494862;8157.632906519221;8161.265486106249;7433.888491672629;7447.627618619047;7464.141623713288;8636.524991125485;8874.201321880997;40180.39729404061;40496.59503404958;40540.594594053975;40570.59429405727;40688.79655601703;3665.1753355495066;3709.0727318170457;3724.3110024645257;3725.608811074825;3763.604821493731;32014.879479218347;32119.357612847845;32211;32237.51643725346;32264.677353226638;3117;3182.0771976126894;3223.854926528312;3236.838158092086;3250.106220789302;239683;256961;304850.4757476221;319128;319252;44626.33060504095;52340.73829630828;52610;52632;53315.73342133265;45977.77011114923;56136.71931640397;56314.71842640843;56619.433805661414;56753.14870276949;15391.230438478031;15454.459093931884;15510.379584816706;15560.377584896478;15591.61020974462;12562.937185314015;12635.43140558677;12680.746385072247;12694;12731.745365092645;14514.564563063124;14571.489997850025;14596.34316455762;14610;14696;6012.39876012404;6049.213602231682;6059.1820104286235;6082.9695851520455;6104.328523862371;829.713748756678;914.2366124286162;997.4962643864947;1023.3143793658267;1043.2019505078622;24078.51842963166;25526;25559.23322300334;25627.87186064058;25744;1157;1158.9775841157234;1162.581470670558;1163.5636636261402;1168.7311918258679;13140.802887956688;13144.60566183016;13170.209787412594;13183.472661093449;13235.470581176838;329667;330936;331041.6895831059;331203;331444;59698.70150649219;60058;60105.39894601086;60160;60224.09663855046;1511.5238699809524;1518.4305885293015;1579.431404694309;1599.2643384043238;1600.367854697388;23204.18785342505;23611.173608923604;23841.761582383955;23871.403214919766;24779.380515486537;41729;41893.79053104715;41964.790176048926;42031.57968420276;42118.57881421147;275425;278893;279959;280148;280581;28987.855060725404;36571.08572285714;36989.63010369862;37480.376373653584;37852.432213516266;46135.53864461312;46166;48620.513794861596;48771.53685389444;48830.26754598613;28354.432911341246;30062.3987520244;31751.523727144573;32465.675343246265;32856.34287314288;30889.8455507721;31730.682693172774;32506;32567.511487327236;32690;39358.01604959899;39735;39881.2023759529;41140.58859411367;41988;28149.874005040387;31660.683393165775;33061;33594;34685.826570866986;24369.39076523101;25361.112361067273;25995.66039056494;26017.349566260997;26427.942882284493 11:42:00 INFO - PID 1995 | |2;jquery.html;527.6913005891553;552.2268823646891;585.3122580967362;587.2014060877209;602.575184494931;14610.050346727456;23750.506196849343;23783.929723162455;24639;24721.75278247217;17189.140542972866;17384;18854.71717924232;22607.660885086738;22690.432739181528;4247.851325203626;4362.454693163355;4383.167198232341;6957.965210173942;6967.989641501979;6713.664316784166;6833.760818371358;6848.7260509579555;6859.416949559293;6867.347601977829;6806.455483561328;6809.28502507237;6815.625140617264;6815.863682726343;6820.045193672865;6806.11520502334;6815.795526134224;6820.045193672914;6824.522283440161;6837.658117094125;5702.116171993348;5985.25184351956;6222.0355844844125;6265.122882796412;6296.527760417941;30835.383292334252;30997.690023099705;31173.8441307792;31305.37389252225;31323.37353252945;2341.1220792202926;2374.34705456001;2377.3105799318178;2382.737898831127;2383.0348708772976;18811.717824232648;18890.80936347917;19271.421857344303;19497.80502194974;19512.609747804963;2274.2494976657663;2337;2343.004223205131;2348.7533808949984;2349.612313968193;152470;176158;179355;186182.2072668911;211802.8819711814;15674.451394201149;16094.75857862133;16291.755623665655;16310.347586096423;16314.510564683078;22143.778562214495;22214.66677999831;22230.999605017812;22237.332880013626;22259.332220033426;9897.40615563054;9942.30403871722;10032.949835250776;10035.69892903214;10040;9076.909230907739;9096.316586606967;9098.226650734628;9101.180893719596;9157.175854173023;7590.696372145052;9326.766830829149;9329.020452852488;9396;9467.100625440573;2841.261272069277;2852.500812357829;2859.3852321750924;2865.212066681679;2949.115265420365;925.8657494663236;975.9902400976041;1000.7548150703125;1000.8198524265556;1002.744300203458;23560.29319120429;25814.741852581235;26623.46753064928;28459.715402845708;28850.42299154005;900.5902314446911;906.7687739626354;1038.9532471038822;1048.570086264628;1081.5241293830848;10637.787244255072;10974.561017559368;11158.497867595977;11201.383923884265;11403.771924561628;624264;641911;649708;652695;656546;148583;151460;152054;152364;152521.23739381455;971.7376308396694;1083.4799296337644;1131.3381671722134;1140.2303445174473;1269.1623528471164;15723.292451839467;18108.637827243645;20045.198192072738;21749.130034799065;23893.92477338559;67057;69383.61232775418;69561.65219173871;70694;70834.64582677053;83110;88367.55816220878;89633.55183224303;89992;93552;11991.22057066288;12228.08289378318;12230.938845305716;12233.755324893631;12263.509459621515;17884.463466096036;18142.727859082388;18904;19011.33460328882;19364.515887102934;15513;15658.843411565738;15735;15785.368585256461;15816.60458488501;13309.866901330863;13568.118072325267;13608.591742247749;14347.56957291283;14684;15592.14243216634;15790.842091579396;15932.36270549165;16009.279582418587;16033.599160021093;12081.75836483283;12239.388030598257;12330.938345308216;12348.753024939271;12461.93769031149;12845.935770321088;14429.350679219251;15251.313690883715;15430.768538471708;15496.690066198387 11:42:00 INFO - PID 1995 | |3;mootools.html;1464.0776310924116;1524.5045360257918;1525.7177422176892;1547.659514906721;1573.4728865829943;15001.774973375395;15620.2970866311;15634.296456659435;15663.295151718172;15691.92154039231;12984.93507532461;13441.193528388303;13472.66318342042;15045.398184072625;15471.845281547181;2879.078694817659;2946.7789915756402;2949.941001179975;2954.423887341969;2961.57057226702;2876.2521744346413;2889.6387951506063;2909.825410475368;2916.650001999763;2920.036387991958;2893.103138027208;2902.2599237194486;2908.8981885634007;2909.229054300612;2920.3721199942006;2479.2562231330617;2566.0120853471362;2566.525192839331;2802.1733588591414;2856.742893139617;2961.8667159977635;2971.1680729395803;3000.204945689394;3003.1290925631542;3038.848057597111;15242.009269397453;15315.463958761502;15402.306896189586;15407.3837046518;15425.845741542553;8217.79455513611;8467.449615775016;8500.447470914432;8526.36052296074;8534.786630334229;4850.660453768238;5156.74216289184;5331.093714068617;5333.813316533902;5376.301080859503;1363.6341194883155;1485.4652325162933;1499.5276487906383;1506.8041154649934;1545.127003243164;23902.88048559746;24088.277351679477;24089.397765055837;24160.15439459628;24201.51596968051;7729.961350193325;7892.171322011106;7911.920880791118;8054;8087.676492940216;19019.33432329862;19123;19155;19155.425337239903;19220.231190752493;7357.301056399633;8682.86975695365;8711.825763484821;8714.52070136136;8736.039035706068;6529.869402611921;7221.8555628887125;7435.330820226206;7612.048493938258;7620.047494063242;5768.625039372428;5921.200637913913;6029.849253768691;6054.606450580698;6093.238345206849;1881.1534809335853;1917.1564511614845;1926.1813729165049;1932.3719791067958;1948.5615736459467;801.8837268596021;850.0734199722209;858.3862538285156;876.373393023991;876.513534988075;6876.587404755631;8339.583020848933;8405.41162118658;8804.647814087493;8958.417702849294;712.4549719464613;712.786164150763;729.8029532026324;752.0636807174993;753.1903204055662;4925.5074492551175;4928.186849169847;5110.105731497048;5124.948750512447;5285.63000589955;16660.50018499447;16664.250108745135;20312.593748124953;20403.591928161353;20413.387598371777;15977.440789572309;20337.389878303675;20487.795122048887;20507;20524;9180.21968132703;9482.95258523703;10211.897881021243;10264.973502649675;10340;7723.34351580122;8487.193716596914;8792.560371981248;8883.955580222058;9012.188902998761;17877.553061173574;18357.54106147357;18468.63062738711;18482.445526634223;18769.530761731065;17773.298668925858;18455.354062607745;18637;18779;18965.715514267573;11413.372264525531;11971.461284242056;12111.81832272499;12178.269303841444;12197.146199765932;13594.660133496345;13736.93131534376;13739.931300343434;13747.312634368442;13788.241646709528;9301.627934882528;10232.283740138118;10412.635557755442;10441.947790261;10516.842247366449;10436.58253669875;10505.9474702626;10711.94644026775;10772.299800512941;10910.181736369645;9813.362131461432;9879;9892.406455612687;9924.603015879284;9956.153726933002;6195.907061393992;7056.08270924797;7200.135983681992;7477.738279160203;7505.66224519887;6339.746410143543;7388.187299397169;7488.775336739906;7698.653560589899;7756.68973241064 11:42:00 INFO - PID 1995 | |4;prototype.html;384.7922122054091;425.40443379269027;460.73968207962497;475.1589686255328;505.67513115637036;6404.743810247591;6540.655564102819;6568.142857856829;6574.342565743424;6586.769463068794;5254.605904557164;6161.47627451666;6329.670769138486;6330.366963303667;6455;4076.653484453825;4721.3390125382475;4724.393706139912;4862.538058884409;4866.780994855222;4818.132736107499;4888.584470320027;4892.804287828482;4926.876828079291;4928.581070608984;4466.754328511931;4579.954200457986;4839.830605928793;4851.126797176506;4871.098846713369;4772.665913386064;4776.952230477686;4795.640326975474;4799.832005879777;4836.9116948686615;4187.392828039917;4189.790510474464;4200.537940826506;4209.147974891069;4237.982884107822;6132.63204207749;6159.1993040904845;6175.62946223228;6182.045199150955;6199.287081985539;4971.676841005323;5623.7908849597125;5681.659100453986;5745.7017193122665;5798.478136967693;5693.686847223401;6631.237407698129;6833.965830170867;6871;6902.964555316693;4719;4725.983913458623;4899.608031357483;4908.877278068041;4913.361263035818;7300.211448195173;7351.059576169575;7366.668499917517;7373.29953654402;7384.446166537531;4736.64475164364;4994.80020799164;5002.074616195942;5006.574441172541;5024.773885175176;4459.576340247671;4716.64625153108;4731.219348807477;5137.8201762938115;5140.254663073833;5055.721935293521;5618.269624948731;5779.133130030445;5820.031783790213;5828.996133982006;5773.249477567973;6483.448906842971;6885.139357580302;6956.130483689539;6963.825904352431;1487.9479218227527;1498.587888330696;1527.8624923756915;1754.3859649122705;1759.9736003959952;980.367662857453;1148.724306166514;1176.9058475321954;1191.7724743514195;1199.3343694249775;5973.850653733692;6321.778737744157;6328.6202827830475;6345.33373995733;6464.709088091047;5970.373110823388;7040.401565866856;7096.793545097243;7139.89290160648;7171.497995140395;5976.701164941735;6476;6488.902666460007;6490.740370385131;6500.512461565307;3641.2353405785084;3656.933832384694;3770.4344348347418;3773.3019391412677;3779.4519794629623;6552;7363.097558294113;7383.926160738324;7390.9260907390235;7397.926020739831;7309.195988441267;7425.1832298447125;7432.702691892372;7471;7480.47636665439;6649.667516624149;6826.0237787309525;7364.410847132217;7365.447591430557;7365.8158546036775;7125.501214914906;7167.133146741346;7241.493095483476;7248.202697703354;7256.4194864409665;6691.43122834545;7031;7031.558530501133;7045.542039767399;7052.471064670068;6106.297775755845;7064.011038454518;7432;7432.814179645552;7456.7390141344795;4075.899507132998;4640.767961601974;4989.700617962933;5009.799608015638;5019.272205530251;5690.715464226855;7125.465590080868;7136.143662760502;7175;7195;6393.136926515047;6854.931450685429;6962.1297337832775;6965.164180298397;6966.5471744336455;6543.5419520633095;6893.310668933066;6893.310668933267;6901.137357830271;6918.654067296515;6623.006549017493;6686.398224159849;6732.932670673426;6736.461083113437;6800.659967001531;6026.131899111289;6811.829704257235;6881.208661004049;6912.170539535288;6919.273476284918;5577.382559057828;5715.51418129447;6446.29090800021;6564.6389448578875;6585.045168450643 11:42:00 INFO - PID 1995 | |5;yui.html;820.1224689582148;870.4951128345552;892.504659913748;893.4371346051983;895.3777124898198;15823.920880395583;17006.40477583285;17536.64926701467;17692.46922592321;17733.46799596011;14776.556703298891;14836.332365043572;14924.179170145637;17031.74452383212;18858.62282754344;1038.4548112241096;1045.7751583409565;1054.69413869978;1062.0600768320053;1064.5156453813497;1049.490996866519;1051.4690081508827;1053.399562249518;1056.0917610854658;1065.4938904020605;1104.817705078665;1109.1514991031868;1110.7722916802036;1112.3326004397377;1114.8717897441818;1046.9162467002623;1081.4106312059946;1085.2457542008308;1099.8625171853519;1104.0670633314837;814.8329592433537;817.0154963269306;818.9262966332999;819.5943008210974;830.9252167304911;17716.20277087534;17720.822791772047;17783.822161778477;17828.643427131385;17935.282588696435;17294.65410691792;17678.55803604907;17765.555861103447;17948.192331345123;17949.371771988048;14859.405623775036;14955.626109347244;15088.321025553876;15128.773068403983;15164.09015459076;3563.251717139404;3588.946165807489;3590.0486371111674;3602.3875941090078;3661.6155303692994;117064;144255.1148977025;160076;161557.92499827605;171592;11939.701507462209;12116.151869369236;13957.79063314051;14061.43754249839;14274.500392486214;26468.735312646626;26656.866715666296;26746;26771.86614066917;27931.58102628462;7062.71749130029;8044.557549334842;8076.798080048045;8170.632321545426;8217.630206640715;4103.835846566104;4144.689148313828;4185.644220241253;4189.664826813848;4191.958080419156;4875.7074575525585;4903.313536104948;4999.575036121971;5004.3494345734825;5013.24801279811;1023.4831410137981;1039.365986748083;1055.37205362809;1055.8363453664695;1056.8626078609884;581.3809038733932;655.4003087175181;664.1366223908996;666.5800545656302;702.4696354252507;11772.646820595395;12039.916407523366;12401.751964960651;12533;12754.61736147917;598.937111603284;638.6211362674002;660.3495556876472;687.8796210663114;693.6462404173908;5151.22731590257;5358.812441564526;5692.658440493584;5729.9713501432225;6018.969905150446;130100.3494982519;131218.34390827984;132647;134574;137382;91571.04788188747;126776;130861.69138308495;130922.34538827435;130965.72585685244;110176.4491177555;111004.44497777459;112390.43804781088;112495;115126;11218;11864.940675296568;12313;12369.381530923598;12718.618441446772;44547.44083957046;45360;48901;50563;51137.48862511476;52252.47747522628;64322.07033788994;88885.55557222173;91445;91604.54197728969;17306.394276200273;17615.559611009827;17670.967838698714;18036.63926721432;18127.184276707318;19262.22951081941;19328.4201473956;19330.936798476218;19787.60424791525;19905;17038.74441883348;17999.2800287987;18267.360642377454;18408.723869141704;18639.90680046591;17765.200565974304;18286.908565457088;18347.633047339245;18432.539186520444;18594.721079184095;17450.91274543619;17493.737593936356;17817.1982260796;18125.456236312933;18154;12083.879161208275;12121.484186007101;13298.268595227355;13432.261225632685;13433.395497202453;13188.010899182791;14297.28513574338;14559.85440145585;14637;14704.338304776096 11:42:00 INFO - PID 1995 | __end_tp_report 11:42:00 INFO - PID 1995 | __start_cc_report 11:42:00 INFO - PID 1995 | _x_x_mozilla_cycle_collect,429 11:42:00 INFO - PID 1995 | __end_cc_report 11:42:00 INFO - PID 1995 | __startTimestamp1498070520544__endTimestamp 11:42:00 INFO - PID 1995 | 11:42:00 INFO - PID 1995 | ------- Summary: start ------- 11:42:00 INFO - PID 1995 | Number of tests: 6 11:42:00 INFO - PID 1995 | 11:42:00 INFO - PID 1995 | [#0] dojo.html Cycles:165 Average:6488.33 Median:6756.16 stddev:854.42 (12.6%) stddev-sans-first:822.46 11:42:00 INFO - PID 1995 | Values: 3420.9 3581.6 3651.6 3659.3 3668.3 5838.9 5881.4 6193.9 6430.0 6445.1 6387.9 6401.0 6403.5 6406.5 6421.4 5574.1 5716.2 5719.2 5733.8 5826.7 4825.7 5867.6 5882.3 5884.9 5899.1 5790.5 5837.7 5853.4 5863.7 5873.9 5601.9 5604.9 5737.1 5771.7 5798.0 5570.9 5598.3 5598.4 5615.5 5638.2 5691.5 6446.9 6495.9 6500.3 6517.4 7156.5 7179.9 7184.1 7190.4 7193.9 6304.4 6454.9 6617.6 7229.0 7323.5 6530.5 6584.4 6726.4 6749.9 6756.2 6776.6 7243.2 7300.6 7323.3 7348.4 5728.3 6805.8 6995.3 7005.5 7053.9 7045.6 7060.1 7072.4 7073.6 7074.8 5947.6 7005.7 7046.7 7082.4 7119.9 7066.3 7083.8 7098.5 7107.0 7116.4 5542.2 5543.1 5544.3 5566.5 5579.0 4056.0 4341.9 4849.3 4870.0 4875.3 6297.9 6627.1 6647.6 6666.2 6669.1 7309.7 7320.3 7323.9 7328.6 7338.2 6713.8 6715.0 6723.2 6727.6 6733.5 6114.2 6245.1 6264.4 6280.2 6285.7 7298.5 7303.1 7315.9 7331.0 7381.0 7152.2 7176.7 7199.1 7260.4 7271.3 7409.9 7410.6 7420.7 7423.3 7477.3 6976.3 7115.6 7143.5 7147.2 7157.3 6942.9 7069.9 7221.8 7234.8 7257.2 6868.3 6955.9 6969.2 6995.0 7039.2 6974.2 6991.3 7025.6 7036.8 7042.8 6779.1 6902.0 6913.9 6920.4 6925.1 6778.5 6883.2 6887.7 7012.5 7049.1 5992.8 6269.9 6680.8 6791.7 6802.9 11:42:00 INFO - PID 1995 | 11:42:00 INFO - PID 1995 | [#1] ext.html Cycles:180 Average:46400.09 Median:20327.40 stddev:78811.16 (387.7%) stddev-sans-first:78958.36 11:42:00 INFO - PID 1995 | Values: 950.4 965.0 978.3 983.7 1005.2 41455.8 42473.8 42528.8 42699.1 42805.4 42312.4 42474.0 42644.4 42688.1 42729.8 8116.9 8221.1 8260.5 8264.8 8282.5 8075.8 8162.6 8196.5 8196.7 8198.2 6557.4 7548.6 8133.0 8154.8 8204.7 6924.2 8136.6 8145.6 8157.6 8161.3 7433.9 7447.6 7464.1 8636.5 8874.2 40180.4 40496.6 40540.6 40570.6 40688.8 3665.2 3709.1 3724.3 3725.6 3763.6 32014.9 32119.4 32211.0 32237.5 32264.7 3117.0 3182.1 3223.9 3236.8 3250.1 239683.0 256961.0 304850.5 319128.0 319252.0 44626.3 52340.7 52610.0 52632.0 53315.7 45977.8 56136.7 56314.7 56619.4 56753.1 15391.2 15454.5 15510.4 15560.4 15591.6 12562.9 12635.4 12680.7 12694.0 12731.7 14514.6 14571.5 14596.3 14610.0 14696.0 6012.4 6049.2 6059.2 6083.0 6104.3 829.7 914.2 997.5 1023.3 1043.2 24078.5 25526.0 25559.2 25627.9 25744.0 1157.0 1159.0 1162.6 1163.6 1168.7 13140.8 13144.6 13170.2 13183.5 13235.5 329667.0 330936.0 331041.7 331203.0 331444.0 59698.7 60058.0 60105.4 60160.0 60224.1 1511.5 1518.4 1579.4 1599.3 1600.4 23204.2 23611.2 23841.8 23871.4 24779.4 41729.0 41893.8 41964.8 42031.6 42118.6 275425.0 278893.0 279959.0 280148.0 280581.0 28987.9 36571.1 36989.6 37480.4 37852.4 46135.5 46166.0 48620.5 48771.5 48830.3 28354.4 30062.4 31751.5 32465.7 32856.3 30889.8 31730.7 32506.0 32567.5 32690.0 39358.0 39735.0 39881.2 41140.6 41988.0 28149.9 31660.7 33061.0 33594.0 34685.8 24369.4 25361.1 25995.7 26017.3 26427.9 11:42:00 INFO - PID 1995 | 11:42:00 INFO - PID 1995 | [#2] jquery.html Cycles:180 Average:41639.61 Median:2342.06 stddev:109459.38 (4673.6%) stddev-sans-first:109722.91 11:42:00 INFO - PID 1995 | Values: 527.7 552.2 585.3 587.2 602.6 14610.1 23750.5 23783.9 24639.0 24721.8 17189.1 17384.0 18854.7 22607.7 22690.4 4247.9 4362.5 4383.2 6958.0 6968.0 6713.7 6833.8 6848.7 6859.4 6867.3 6806.5 6809.3 6815.6 6815.9 6820.0 6806.1 6815.8 6820.0 6824.5 6837.7 5702.1 5985.3 6222.0 6265.1 6296.5 30835.4 30997.7 31173.8 31305.4 31323.4 2341.1 2374.3 2377.3 2382.7 2383.0 18811.7 18890.8 19271.4 19497.8 19512.6 2274.2 2337.0 2343.0 2348.8 2349.6 152470.0 176158.0 179355.0 186182.2 211802.9 15674.5 16094.8 16291.8 16310.3 16314.5 22143.8 22214.7 22231.0 22237.3 22259.3 9897.4 9942.3 10032.9 10035.7 10040.0 9076.9 9096.3 9098.2 9101.2 9157.2 7590.7 9326.8 9329.0 9396.0 9467.1 2841.3 2852.5 2859.4 2865.2 2949.1 925.9 976.0 1000.8 1000.8 1002.7 23560.3 25814.7 26623.5 28459.7 28850.4 900.6 906.8 1039.0 1048.6 1081.5 10637.8 10974.6 11158.5 11201.4 11403.8 624264.0 641911.0 649708.0 652695.0 656546.0 148583.0 151460.0 152054.0 152364.0 152521.2 971.7 1083.5 1131.3 1140.2 1269.2 15723.3 18108.6 20045.2 21749.1 23893.9 67057.0 69383.6 69561.7 70694.0 70834.6 83110.0 88367.6 89633.6 89992.0 93552.0 11991.2 12228.1 12230.9 12233.8 12263.5 17884.5 18142.7 18904.0 19011.3 19364.5 15513.0 15658.8 15735.0 15785.4 15816.6 13309.9 13568.1 13608.6 14347.6 14684.0 15592.1 15790.8 15932.4 16009.3 16033.6 12081.8 12239.4 12330.9 12348.8 12461.9 12845.9 14429.4 15251.3 15430.8 15496.7 11:42:00 INFO - PID 1995 | 11:42:00 INFO - PID 1995 | [#3] mootools.html Cycles:180 Average:9179.07 Median:2897.68 stddev:6273.35 (216.5%) stddev-sans-first:6264.17 11:42:00 INFO - PID 1995 | Values: 1464.1 1524.5 1525.7 1547.7 1573.5 15001.8 15620.3 15634.3 15663.3 15691.9 12984.9 13441.2 13472.7 15045.4 15471.8 2879.1 2946.8 2949.9 2954.4 2961.6 2876.3 2889.6 2909.8 2916.7 2920.0 2893.1 2902.3 2908.9 2909.2 2920.4 2479.3 2566.0 2566.5 2802.2 2856.7 2961.9 2971.2 3000.2 3003.1 3038.8 15242.0 15315.5 15402.3 15407.4 15425.8 8217.8 8467.4 8500.4 8526.4 8534.8 4850.7 5156.7 5331.1 5333.8 5376.3 1363.6 1485.5 1499.5 1506.8 1545.1 23902.9 24088.3 24089.4 24160.2 24201.5 7730.0 7892.2 7911.9 8054.0 8087.7 19019.3 19123.0 19155.0 19155.4 19220.2 7357.3 8682.9 8711.8 8714.5 8736.0 6529.9 7221.9 7435.3 7612.0 7620.0 5768.6 5921.2 6029.8 6054.6 6093.2 1881.2 1917.2 1926.2 1932.4 1948.6 801.9 850.1 858.4 876.4 876.5 6876.6 8339.6 8405.4 8804.6 8958.4 712.5 712.8 729.8 752.1 753.2 4925.5 4928.2 5110.1 5124.9 5285.6 16660.5 16664.3 20312.6 20403.6 20413.4 15977.4 20337.4 20487.8 20507.0 20524.0 9180.2 9483.0 10211.9 10265.0 10340.0 7723.3 8487.2 8792.6 8884.0 9012.2 17877.6 18357.5 18468.6 18482.4 18769.5 17773.3 18455.4 18637.0 18779.0 18965.7 11413.4 11971.5 12111.8 12178.3 12197.1 13594.7 13736.9 13739.9 13747.3 13788.2 9301.6 10232.3 10412.6 10441.9 10516.8 10436.6 10505.9 10711.9 10772.3 10910.2 9813.4 9879.0 9892.4 9924.6 9956.2 6195.9 7056.1 7200.1 7477.7 7505.7 6339.7 7388.2 7488.8 7698.7 7756.7 11:42:00 INFO - PID 1995 | 11:42:00 INFO - PID 1995 | [#4] prototype.html Cycles:180 Average:5633.90 Median:6260.53 stddev:1730.80 (27.6%) stddev-sans-first:1690.22 11:42:00 INFO - PID 1995 | Values: 384.8 425.4 460.7 475.2 505.7 6404.7 6540.7 6568.1 6574.3 6586.8 5254.6 6161.5 6329.7 6330.4 6455.0 4076.7 4721.3 4724.4 4862.5 4866.8 4818.1 4888.6 4892.8 4926.9 4928.6 4466.8 4580.0 4839.8 4851.1 4871.1 4772.7 4777.0 4795.6 4799.8 4836.9 4187.4 4189.8 4200.5 4209.1 4238.0 6132.6 6159.2 6175.6 6182.0 6199.3 4971.7 5623.8 5681.7 5745.7 5798.5 5693.7 6631.2 6834.0 6871.0 6903.0 4719.0 4726.0 4899.6 4908.9 4913.4 7300.2 7351.1 7366.7 7373.3 7384.4 4736.6 4994.8 5002.1 5006.6 5024.8 4459.6 4716.6 4731.2 5137.8 5140.3 5055.7 5618.3 5779.1 5820.0 5829.0 5773.2 6483.4 6885.1 6956.1 6963.8 1487.9 1498.6 1527.9 1754.4 1760.0 980.4 1148.7 1176.9 1191.8 1199.3 5973.9 6321.8 6328.6 6345.3 6464.7 5970.4 7040.4 7096.8 7139.9 7171.5 5976.7 6476.0 6488.9 6490.7 6500.5 3641.2 3656.9 3770.4 3773.3 3779.5 6552.0 7363.1 7383.9 7390.9 7397.9 7309.2 7425.2 7432.7 7471.0 7480.5 6649.7 6826.0 7364.4 7365.4 7365.8 7125.5 7167.1 7241.5 7248.2 7256.4 6691.4 7031.0 7031.6 7045.5 7052.5 6106.3 7064.0 7432.0 7432.8 7456.7 4075.9 4640.8 4989.7 5009.8 5019.3 5690.7 7125.5 7136.1 7175.0 7195.0 6393.1 6854.9 6962.1 6965.2 6966.5 6543.5 6893.3 6893.3 6901.1 6918.7 6623.0 6686.4 6732.9 6736.5 6800.7 6026.1 6811.8 6881.2 6912.2 6919.3 5577.4 5715.5 6446.3 6564.6 6585.0 11:42:00 INFO - PID 1995 | 11:42:00 INFO - PID 1995 | [#5] yui.html Cycles:180 Average:26275.44 Median:17718.51 stddev:39987.17 (225.7%) stddev-sans-first:40053.67 11:42:00 INFO - PID 1995 | Values: 820.1 870.5 892.5 893.4 895.4 15823.9 17006.4 17536.6 17692.5 17733.5 14776.6 14836.3 14924.2 17031.7 18858.6 1038.5 1045.8 1054.7 1062.1 1064.5 1049.5 1051.5 1053.4 1056.1 1065.5 1104.8 1109.2 1110.8 1112.3 1114.9 1046.9 1081.4 1085.2 1099.9 1104.1 814.8 817.0 818.9 819.6 830.9 17716.2 17720.8 17783.8 17828.6 17935.3 17294.7 17678.6 17765.6 17948.2 17949.4 14859.4 14955.6 15088.3 15128.8 15164.1 3563.3 3588.9 3590.0 3602.4 3661.6 117064.0 144255.1 160076.0 161557.9 171592.0 11939.7 12116.2 13957.8 14061.4 14274.5 26468.7 26656.9 26746.0 26771.9 27931.6 7062.7 8044.6 8076.8 8170.6 8217.6 4103.8 4144.7 4185.6 4189.7 4192.0 4875.7 4903.3 4999.6 5004.3 5013.2 1023.5 1039.4 1055.4 1055.8 1056.9 581.4 655.4 664.1 666.6 702.5 11772.6 12039.9 12401.8 12533.0 12754.6 598.9 638.6 660.3 687.9 693.6 5151.2 5358.8 5692.7 5730.0 6019.0 130100.3 131218.3 132647.0 134574.0 137382.0 91571.0 126776.0 130861.7 130922.3 130965.7 110176.4 111004.4 112390.4 112495.0 115126.0 11218.0 11864.9 12313.0 12369.4 12718.6 44547.4 45360.0 48901.0 50563.0 51137.5 52252.5 64322.1 88885.6 91445.0 91604.5 17306.4 17615.6 17671.0 18036.6 18127.2 19262.2 19328.4 19330.9 19787.6 19905.0 17038.7 17999.3 18267.4 18408.7 18639.9 17765.2 18286.9 18347.6 18432.5 18594.7 17450.9 17493.7 17817.2 18125.5 18154.0 12083.9 12121.5 13298.3 13432.3 13433.4 13188.0 14297.3 14559.9 14637.0 14704.3 11:42:00 INFO - PID 1995 | -------- Summary: end -------- 11:42:00 INFO - PID 1995 | 11:42:01 INFO - TEST-INFO | 1995: exit 0 11:42:01 INFO - TEST-OK | dromaeo_css | took 1117081ms 11:42:01 INFO - TEST-START | kraken 11:42:01 INFO - operating with platform_type : mac_ 11:42:01 INFO - Initialising browser for kraken test... 11:42:01 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:51218/getInfo.html -foreground -profile /var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/tmpaCM5gR/profile 11:42:01 INFO - TEST-INFO | started process 2114 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:51218/getInfo.html -foreground) 11:42:05 INFO - TEST-INFO | 2114: exit 0 11:42:05 INFO - Browser initialized. 11:42:05 INFO - Running cycle 1/1 for kraken test... 11:42:05 INFO - TEST-INFO | started process 2120 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/tmpaCM5gR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:42:07 INFO - PID 2120 | 2017-06-21 11:42:07.480 plugin-container[2121:15016] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 11:42:07 INFO - PID 2120 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:42:07 INFO - PID 2120 | 2017-06-21 11:42:07.481 plugin-container[2121:15016] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c23, name = 'com.apple.CFPasteboardClient' 11:42:07 INFO - PID 2120 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:42:07 INFO - PID 2120 | 2017-06-21 11:42:07.481 plugin-container[2121:15016] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:42:08 INFO - PID 2120 | 2017-06-21 11:42:08.271 plugin-container[2122:15065] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953b, name = 'com.apple.tsm.portname' 11:42:08 INFO - PID 2120 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:42:08 INFO - PID 2120 | 2017-06-21 11:42:08.272 plugin-container[2122:15065] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a1b, name = 'com.apple.CFPasteboardClient' 11:42:08 INFO - PID 2120 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:42:08 INFO - PID 2120 | 2017-06-21 11:42:08.272 plugin-container[2122:15065] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:42:17 INFO - PID 2120 | RSS: Main: 249540608 11:42:17 INFO - PID 2120 | 11:42:20 INFO - PID 2120 | Cycle 1(1): loaded http://localhost:51218/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51218/tests/kraken/driver.html?testName=audio-beat-detection) 11:42:21 INFO - PID 2120 | RSS: Main: 260747264 11:42:21 INFO - PID 2120 | 11:42:24 INFO - PID 2120 | Cycle 1(1): loaded http://localhost:51218/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51218/tests/kraken/driver.html?testName=audio-dft) 11:42:24 INFO - PID 2120 | RSS: Main: 270311424 11:42:24 INFO - PID 2120 | 11:42:28 INFO - PID 2120 | Cycle 1(1): loaded http://localhost:51218/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51218/tests/kraken/driver.html?testName=audio-fft) 11:42:28 INFO - PID 2120 | RSS: Main: 273207296 11:42:28 INFO - PID 2120 | 11:42:30 INFO - PID 2120 | Cycle 1(1): loaded http://localhost:51218/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51218/tests/kraken/driver.html?testName=audio-oscillator) 11:42:31 INFO - PID 2120 | RSS: Main: 273399808 11:42:31 INFO - PID 2120 | 11:42:33 INFO - PID 2120 | Cycle 1(1): loaded http://localhost:51218/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51218/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:42:33 INFO - PID 2120 | RSS: Main: 275021824 11:42:33 INFO - PID 2120 | 11:42:36 INFO - PID 2120 | Cycle 1(1): loaded http://localhost:51218/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51218/tests/kraken/driver.html?testName=imaging-darkroom) 11:42:37 INFO - PID 2120 | RSS: Main: 285057024 11:42:37 INFO - PID 2120 | 11:42:40 INFO - PID 2120 | Cycle 1(1): loaded http://localhost:51218/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51218/tests/kraken/driver.html?testName=imaging-desaturate) 11:42:40 INFO - PID 2120 | RSS: Main: 285777920 11:42:40 INFO - PID 2120 | 11:42:43 INFO - PID 2120 | Cycle 1(1): loaded http://localhost:51218/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51218/tests/kraken/driver.html?testName=json-parse-financial) 11:42:44 INFO - PID 2120 | RSS: Main: 285065216 11:42:44 INFO - PID 2120 | 11:42:45 INFO - PID 2120 | Cycle 1(1): loaded http://localhost:51218/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51218/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:42:46 INFO - PID 2120 | RSS: Main: 285163520 11:42:46 INFO - PID 2120 | 11:42:48 INFO - PID 2120 | Cycle 1(1): loaded http://localhost:51218/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51218/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:42:48 INFO - PID 2120 | RSS: Main: 284905472 11:42:48 INFO - PID 2120 | 11:42:50 INFO - PID 2120 | Cycle 1(1): loaded http://localhost:51218/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51218/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:42:51 INFO - PID 2120 | RSS: Main: 284770304 11:42:51 INFO - PID 2120 | 11:42:54 INFO - PID 2120 | Cycle 1(1): loaded http://localhost:51218/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51218/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:42:55 INFO - PID 2120 | RSS: Main: 284459008 11:42:55 INFO - PID 2120 | 11:42:58 INFO - PID 2120 | Cycle 1(1): loaded http://localhost:51218/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51218/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:42:59 INFO - PID 2120 | RSS: Main: 284598272 11:42:59 INFO - PID 2120 | 11:43:00 INFO - PID 2120 | Cycle 1(1): loaded http://localhost:51218/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51218/tests/kraken/driver.html?testName=ai-astar) 11:43:00 INFO - PID 2120 | RSS: Main: 285917184 11:43:00 INFO - PID 2120 | 11:43:00 INFO - PID 2120 | __start_tp_report 11:43:00 INFO - PID 2120 | _x_x_mozilla_page_load 11:43:00 INFO - PID 2120 | _x_x_mozilla_page_load_details 11:43:00 INFO - PID 2120 | |i|pagename|runs| 11:43:00 INFO - PID 2120 | |0;ai-astar;106;114;116;118;129;166;99;166;167;165 11:43:00 INFO - PID 2120 | |1;audio-beat-detection;147;118;143;145;142;116;138;181;148;108 11:43:00 INFO - PID 2120 | |2;audio-dft;202;199;202;217;223;219;216;202;233;224 11:43:00 INFO - PID 2120 | |3;audio-fft;68;86;76;136;56;58;64;63;103;61 11:43:00 INFO - PID 2120 | |4;audio-oscillator;69;70;111;69;71;75;123;97;75;105 11:43:00 INFO - PID 2120 | |5;imaging-gaussian-blur;88;87;88;88;89;87;88;88;90;95 11:43:00 INFO - PID 2120 | |6;imaging-darkroom;96;97;96;98;96;96;95;98;96;96 11:43:00 INFO - PID 2120 | |7;imaging-desaturate;92;93;93;92;91;93;132;96;139;149 11:43:00 INFO - PID 2120 | |8;json-parse-financial;52;49;54;53;55;51;53;52;52;52 11:43:00 INFO - PID 2120 | |9;json-stringify-tinderbox;55;58;59;52;56;56;52;54;53;53 11:43:00 INFO - PID 2120 | |10;stanford-crypto-aes;86;81;82;81;80;83;81;83;83;82 11:43:00 INFO - PID 2120 | |11;stanford-crypto-ccm;135;136;140;156;146;155;150;157;138;171 11:43:00 INFO - PID 2120 | |12;stanford-crypto-pbkdf2;185;222;223;186;185;182;206;220;193;183 11:43:00 INFO - PID 2120 | |13;stanford-crypto-sha256-iterative;68;66;64;63;65;65;63;66;70;63 11:43:00 INFO - PID 2120 | __end_tp_report 11:43:00 INFO - PID 2120 | __start_cc_report 11:43:00 INFO - PID 2120 | _x_x_mozilla_cycle_collect,1088 11:43:00 INFO - PID 2120 | __end_cc_report 11:43:00 INFO - PID 2120 | __startTimestamp1498070580927__endTimestamp 11:43:00 INFO - PID 2120 | 11:43:00 INFO - PID 2120 | ------- Summary: start ------- 11:43:00 INFO - PID 2120 | Number of tests: 14 11:43:00 INFO - PID 2120 | 11:43:00 INFO - PID 2120 | [#0] ai-astar Cycles:10 Average:134.60 Median:165.50 stddev:28.10 (17.0%) stddev-sans-first:27.84 11:43:00 INFO - PID 2120 | Values: 106.0 114.0 116.0 118.0 129.0 166.0 99.0 166.0 167.0 165.0 11:43:00 INFO - PID 2120 | 11:43:00 INFO - PID 2120 | [#1] audio-beat-detection Cycles:10 Average:138.60 Median:144.00 stddev:20.82 (14.5%) stddev-sans-first:21.86 11:43:00 INFO - PID 2120 | Values: 147.0 118.0 143.0 145.0 142.0 116.0 138.0 181.0 148.0 108.0 11:43:00 INFO - PID 2120 | 11:43:00 INFO - PID 2120 | [#2] audio-dft Cycles:10 Average:213.70 Median:218.00 stddev:11.72 (5.4%) stddev-sans-first:11.64 11:43:00 INFO - PID 2120 | Values: 202.0 199.0 202.0 217.0 223.0 219.0 216.0 202.0 233.0 224.0 11:43:00 INFO - PID 2120 | 11:43:00 INFO - PID 2120 | [#3] audio-fft Cycles:10 Average:77.10 Median:63.50 stddev:25.26 (39.8%) stddev-sans-first:26.58 11:43:00 INFO - PID 2120 | Values: 68.0 86.0 76.0 136.0 56.0 58.0 64.0 63.0 103.0 61.0 11:43:00 INFO - PID 2120 | 11:43:00 INFO - PID 2120 | [#4] audio-oscillator Cycles:10 Average:86.50 Median:70.50 stddev:20.48 (29.0%) stddev-sans-first:20.72 11:43:00 INFO - PID 2120 | Values: 69.0 70.0 111.0 69.0 71.0 75.0 123.0 97.0 75.0 105.0 11:43:00 INFO - PID 2120 | 11:43:00 INFO - PID 2120 | [#5] imaging-gaussian-blur Cycles:10 Average:88.80 Median:88.00 stddev:2.35 (2.7%) stddev-sans-first:2.47 11:43:00 INFO - PID 2120 | Values: 88.0 87.0 88.0 88.0 89.0 87.0 88.0 88.0 90.0 95.0 11:43:00 INFO - PID 2120 | 11:43:00 INFO - PID 2120 | [#6] imaging-darkroom Cycles:10 Average:96.40 Median:96.00 stddev:0.97 (1.0%) stddev-sans-first:1.01 11:43:00 INFO - PID 2120 | Values: 96.0 97.0 96.0 98.0 96.0 96.0 95.0 98.0 96.0 96.0 11:43:00 INFO - PID 2120 | 11:43:00 INFO - PID 2120 | [#7] imaging-desaturate Cycles:10 Average:107.00 Median:92.50 stddev:23.16 (25.0%) stddev-sans-first:23.92 11:43:00 INFO - PID 2120 | Values: 92.0 93.0 93.0 92.0 91.0 93.0 132.0 96.0 139.0 149.0 11:43:00 INFO - PID 2120 | 11:43:00 INFO - PID 2120 | [#8] json-parse-financial Cycles:10 Average:52.30 Median:52.50 stddev:1.64 (3.1%) stddev-sans-first:1.73 11:43:00 INFO - PID 2120 | Values: 52.0 49.0 54.0 53.0 55.0 51.0 53.0 52.0 52.0 52.0 11:43:00 INFO - PID 2120 | 11:43:00 INFO - PID 2120 | [#9] json-stringify-tinderbox Cycles:10 Average:54.80 Median:55.50 stddev:2.44 (4.4%) stddev-sans-first:2.59 11:43:00 INFO - PID 2120 | Values: 55.0 58.0 59.0 52.0 56.0 56.0 52.0 54.0 53.0 53.0 11:43:00 INFO - PID 2120 | 11:43:00 INFO - PID 2120 | [#10] stanford-crypto-aes Cycles:10 Average:82.20 Median:82.50 stddev:1.69 (2.0%) stddev-sans-first:1.09 11:43:00 INFO - PID 2120 | Values: 86.0 81.0 82.0 81.0 80.0 83.0 81.0 83.0 83.0 82.0 11:43:00 INFO - PID 2120 | 11:43:00 INFO - PID 2120 | [#11] stanford-crypto-ccm Cycles:10 Average:148.40 Median:152.50 stddev:11.58 (7.6%) stddev-sans-first:11.22 11:43:00 INFO - PID 2120 | Values: 135.0 136.0 140.0 156.0 146.0 155.0 150.0 157.0 138.0 171.0 11:43:00 INFO - PID 2120 | 11:43:00 INFO - PID 2120 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:198.50 Median:199.50 stddev:17.43 (8.7%) stddev-sans-first:17.79 11:43:00 INFO - PID 2120 | Values: 185.0 222.0 223.0 186.0 185.0 182.0 206.0 220.0 193.0 183.0 11:43:00 INFO - PID 2120 | 11:43:00 INFO - PID 2120 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:65.50 stddev:2.31 (3.5%) stddev-sans-first:2.24 11:43:00 INFO - PID 2120 | Values: 68.0 66.0 64.0 63.0 65.0 65.0 63.0 66.0 70.0 63.0 11:43:00 INFO - PID 2120 | -------- Summary: end -------- 11:43:00 INFO - PID 2120 | 11:43:01 INFO - TEST-INFO | 2120: exit 0 11:43:01 INFO - TEST-OK | kraken | took 60581ms 11:43:01 INFO - SUITE-END | took 1177s 11:43:01 INFO - Completed test suite (00:19:37) 11:43:01 INFO - javascript benchmark 11:43:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8707.191759763451, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3420.880269190578, 3581.64183581642, 3651.6165802590726, 3659.2864391443673, 3668.3030224257404, 5838.941610583893, 5881.382454842244, 6193.938060619393, 6430.0, 6445.097686323918, 6387.90418143727, 6401.0398440233985, 6403.487720982322, 6406.455451286634, 6421.422072013518, 5574.052411090104, 5716.22830917827, 5719.227904232917, 5733.827985160452, 5826.708664566776, 4825.672939941509, 5867.589268751189, 5882.264716910387, 5884.852878678024, 5899.144624029514, 5790.536757059467, 5837.737301821407, 5853.385394533577, 5863.706814659271, 5873.911891321633, 5601.887962240773, 5604.943950560524, 5737.053386191274, 5771.653700777924, 5797.971010144965, 5570.944290557083, 5598.328200615912, 5598.356189038272, 5615.522680572156, 5638.182463542805, 5691.459311365414, 6446.871062578769, 6495.9350406495805, 6500.349965003509, 6517.446017088553, 7156.499045066847, 7179.928200717978, 7184.1019872515935, 7190.424766018706, 7193.89209161863, 6304.432601065881, 6454.93545064548, 6617.569857959217, 7229.0, 7323.450741194485, 6530.477561795045, 6584.374484423972, 6726.39462448382, 6749.920012797928, 6756.155480564929, 6776.627285499247, 7243.239459856744, 7300.6349682515665, 7323.267673232741, 7348.412127029825, 5728.255326807488, 6805.761798337035, 6995.300469952964, 7005.509614326948, 7053.929460705327, 7045.577265363992, 7060.082189315459, 7072.398846098036, 7073.61095139762, 7074.75238366665, 5947.6431414115295, 7005.719771209197, 7046.6828992695455, 7082.433405327562, 7119.857602848017, 7066.258042905421, 7083.7520686775715, 7098.503104782719, 7107.0, 7116.359527642537, 5542.196381524656, 5543.1408131739645, 5544.251526043932, 5566.526845218121, 5579.0, 4055.97972010144, 4341.934870976938, 4849.2968519564465, 4870.001649661811, 4875.317455556225, 6297.874042519124, 6627.072209890619, 6647.567908085959, 6666.200055993215, 6669.066330713704, 7309.744158954411, 7320.304571065741, 7323.853522929511, 7328.560286382727, 7338.156112047183, 6713.765018224436, 6714.9664251679405, 6723.193216814014, 6727.629980351127, 6733.46132309414, 6114.174586430864, 6245.094461303085, 6264.404881536338, 6280.214973128359, 6285.685715714196, 7298.4891057625455, 7303.050603421733, 7315.8536829264185, 7331.04696389466, 7380.96309518449, 7152.213256541881, 7176.7129314826825, 7199.06412166415, 7260.382867456326, 7271.309225623558, 7409.925900740924, 7410.592417416879, 7420.740274090381, 7423.294786995227, 7477.252274772479, 6976.267491913276, 7115.644217789193, 7143.535670181422, 7147.249538798351, 7157.320054594805, 6942.895856562054, 7069.858602828018, 7221.819454513679, 7234.819129521594, 7257.165425975869, 6868.347506986828, 6955.8956615649795, 6969.1985421677155, 6994.985727069648, 7039.1553013638695, 6974.232834388315, 6991.335823096798, 7025.613591252531, 7036.824079398056, 7042.7887163385185, 6779.118714567272, 6901.965490172517, 6913.896291555731, 6920.446364290945, 6925.099737034355, 6778.491613129134, 6883.242843287335, 6887.6556172192195, 7012.544184627983, 7049.118860142482, 5992.833681695959, 6269.934111201198, 6680.766173183915, 6791.72833086671, 6802.863942721019], "value": 6425.030175895023, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [950.4439902656948, 965.0446058402183, 978.2711879649662, 983.7358993693101, 1005.1707341443312, 41455.79272103639, 42473.78763106188, 42528.78735606325, 42699.14601707964, 42805.35791963119, 42312.36531452023, 42474.0, 42644.360334595, 42688.146237075314, 42729.78635106828, 8116.91883081169, 8221.136780638037, 8260.504369737804, 8264.83470330593, 8282.461639993411, 8075.838483230332, 8162.632681529316, 8196.54918979456, 8196.67213311467, 8198.221168988966, 6557.377049180321, 7548.5848278344665, 8132.959335203315, 8154.75535733926, 8204.712835050774, 6924.23833378328, 8136.633851476698, 8145.633446494862, 8157.632906519221, 8161.265486106249, 7433.888491672629, 7447.627618619047, 7464.141623713288, 8636.524991125485, 8874.201321880997, 40180.39729404061, 40496.59503404958, 40540.594594053975, 40570.59429405727, 40688.79655601703, 3665.1753355495066, 3709.0727318170457, 3724.3110024645257, 3725.608811074825, 3763.604821493731, 32014.879479218347, 32119.357612847845, 32211.0, 32237.51643725346, 32264.677353226638, 3117.0, 3182.0771976126894, 3223.854926528312, 3236.838158092086, 3250.106220789302, 239683.0, 256961.0, 304850.4757476221, 319128.0, 319252.0, 44626.33060504095, 52340.73829630828, 52610.0, 52632.0, 53315.73342133265, 45977.77011114923, 56136.71931640397, 56314.71842640843, 56619.433805661414, 56753.14870276949, 15391.230438478031, 15454.459093931884, 15510.379584816706, 15560.377584896478, 15591.61020974462, 12562.937185314015, 12635.43140558677, 12680.746385072247, 12694.0, 12731.745365092645, 14514.564563063124, 14571.489997850025, 14596.34316455762, 14610.0, 14696.0, 6012.39876012404, 6049.213602231682, 6059.1820104286235, 6082.9695851520455, 6104.328523862371, 829.713748756678, 914.2366124286162, 997.4962643864947, 1023.3143793658267, 1043.2019505078622, 24078.51842963166, 25526.0, 25559.23322300334, 25627.87186064058, 25744.0, 1157.0, 1158.9775841157234, 1162.581470670558, 1163.5636636261402, 1168.7311918258679, 13140.802887956688, 13144.60566183016, 13170.209787412594, 13183.472661093449, 13235.470581176838, 329667.0, 330936.0, 331041.6895831059, 331203.0, 331444.0, 59698.70150649219, 60058.0, 60105.39894601086, 60160.0, 60224.09663855046, 1511.5238699809524, 1518.4305885293015, 1579.431404694309, 1599.2643384043238, 1600.367854697388, 23204.18785342505, 23611.173608923604, 23841.761582383955, 23871.403214919766, 24779.380515486537, 41729.0, 41893.79053104715, 41964.790176048926, 42031.57968420276, 42118.57881421147, 275425.0, 278893.0, 279959.0, 280148.0, 280581.0, 28987.855060725404, 36571.08572285714, 36989.63010369862, 37480.376373653584, 37852.432213516266, 46135.53864461312, 46166.0, 48620.513794861596, 48771.53685389444, 48830.26754598613, 28354.432911341246, 30062.3987520244, 31751.523727144573, 32465.675343246265, 32856.34287314288, 30889.8455507721, 31730.682693172774, 32506.0, 32567.511487327236, 32690.0, 39358.01604959899, 39735.0, 39881.2023759529, 41140.58859411367, 41988.0, 28149.874005040387, 31660.683393165775, 33061.0, 33594.0, 34685.826570866986, 24369.39076523101, 25361.112361067273, 25995.66039056494, 26017.349566260997, 26427.942882284493], "value": 18193.294383231198, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [527.6913005891553, 552.2268823646891, 585.3122580967362, 587.2014060877209, 602.575184494931, 14610.050346727456, 23750.506196849343, 23783.929723162455, 24639.0, 24721.75278247217, 17189.140542972866, 17384.0, 18854.71717924232, 22607.660885086738, 22690.432739181528, 4247.851325203626, 4362.454693163355, 4383.167198232341, 6957.965210173942, 6967.989641501979, 6713.664316784166, 6833.760818371358, 6848.7260509579555, 6859.416949559293, 6867.347601977829, 6806.455483561328, 6809.28502507237, 6815.625140617264, 6815.863682726343, 6820.045193672865, 6806.11520502334, 6815.795526134224, 6820.045193672914, 6824.522283440161, 6837.658117094125, 5702.116171993348, 5985.25184351956, 6222.0355844844125, 6265.122882796412, 6296.527760417941, 30835.383292334252, 30997.690023099705, 31173.8441307792, 31305.37389252225, 31323.37353252945, 2341.1220792202926, 2374.34705456001, 2377.3105799318178, 2382.737898831127, 2383.0348708772976, 18811.717824232648, 18890.80936347917, 19271.421857344303, 19497.80502194974, 19512.609747804963, 2274.2494976657663, 2337.0, 2343.004223205131, 2348.7533808949984, 2349.612313968193, 152470.0, 176158.0, 179355.0, 186182.2072668911, 211802.8819711814, 15674.451394201149, 16094.75857862133, 16291.755623665655, 16310.347586096423, 16314.510564683078, 22143.778562214495, 22214.66677999831, 22230.999605017812, 22237.332880013626, 22259.332220033426, 9897.40615563054, 9942.30403871722, 10032.949835250776, 10035.69892903214, 10040.0, 9076.909230907739, 9096.316586606967, 9098.226650734628, 9101.180893719596, 9157.175854173023, 7590.696372145052, 9326.766830829149, 9329.020452852488, 9396.0, 9467.100625440573, 2841.261272069277, 2852.500812357829, 2859.3852321750924, 2865.212066681679, 2949.115265420365, 925.8657494663236, 975.9902400976041, 1000.7548150703125, 1000.8198524265556, 1002.744300203458, 23560.29319120429, 25814.741852581235, 26623.46753064928, 28459.715402845708, 28850.42299154005, 900.5902314446911, 906.7687739626354, 1038.9532471038822, 1048.570086264628, 1081.5241293830848, 10637.787244255072, 10974.561017559368, 11158.497867595977, 11201.383923884265, 11403.771924561628, 624264.0, 641911.0, 649708.0, 652695.0, 656546.0, 148583.0, 151460.0, 152054.0, 152364.0, 152521.23739381455, 971.7376308396694, 1083.4799296337644, 1131.3381671722134, 1140.2303445174473, 1269.1623528471164, 15723.292451839467, 18108.637827243645, 20045.198192072738, 21749.130034799065, 23893.92477338559, 67057.0, 69383.61232775418, 69561.65219173871, 70694.0, 70834.64582677053, 83110.0, 88367.55816220878, 89633.55183224303, 89992.0, 93552.0, 11991.22057066288, 12228.08289378318, 12230.938845305716, 12233.755324893631, 12263.509459621515, 17884.463466096036, 18142.727859082388, 18904.0, 19011.33460328882, 19364.515887102934, 15513.0, 15658.843411565738, 15735.0, 15785.368585256461, 15816.60458488501, 13309.866901330863, 13568.118072325267, 13608.591742247749, 14347.56957291283, 14684.0, 15592.14243216634, 15790.842091579396, 15932.36270549165, 16009.279582418587, 16033.599160021093, 12081.75836483283, 12239.388030598257, 12330.938345308216, 12348.753024939271, 12461.93769031149, 12845.935770321088, 14429.350679219251, 15251.313690883715, 15430.768538471708, 15496.690066198387], "value": 12011.802356995575, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1464.0776310924116, 1524.5045360257918, 1525.7177422176892, 1547.659514906721, 1573.4728865829943, 15001.774973375395, 15620.2970866311, 15634.296456659435, 15663.295151718172, 15691.92154039231, 12984.93507532461, 13441.193528388303, 13472.66318342042, 15045.398184072625, 15471.845281547181, 2879.078694817659, 2946.7789915756402, 2949.941001179975, 2954.423887341969, 2961.57057226702, 2876.2521744346413, 2889.6387951506063, 2909.825410475368, 2916.650001999763, 2920.036387991958, 2893.103138027208, 2902.2599237194486, 2908.8981885634007, 2909.229054300612, 2920.3721199942006, 2479.2562231330617, 2566.0120853471362, 2566.525192839331, 2802.1733588591414, 2856.742893139617, 2961.8667159977635, 2971.1680729395803, 3000.204945689394, 3003.1290925631542, 3038.848057597111, 15242.009269397453, 15315.463958761502, 15402.306896189586, 15407.3837046518, 15425.845741542553, 8217.79455513611, 8467.449615775016, 8500.447470914432, 8526.36052296074, 8534.786630334229, 4850.660453768238, 5156.74216289184, 5331.093714068617, 5333.813316533902, 5376.301080859503, 1363.6341194883155, 1485.4652325162933, 1499.5276487906383, 1506.8041154649934, 1545.127003243164, 23902.88048559746, 24088.277351679477, 24089.397765055837, 24160.15439459628, 24201.51596968051, 7729.961350193325, 7892.171322011106, 7911.920880791118, 8054.0, 8087.676492940216, 19019.33432329862, 19123.0, 19155.0, 19155.425337239903, 19220.231190752493, 7357.301056399633, 8682.86975695365, 8711.825763484821, 8714.52070136136, 8736.039035706068, 6529.869402611921, 7221.8555628887125, 7435.330820226206, 7612.048493938258, 7620.047494063242, 5768.625039372428, 5921.200637913913, 6029.849253768691, 6054.606450580698, 6093.238345206849, 1881.1534809335853, 1917.1564511614845, 1926.1813729165049, 1932.3719791067958, 1948.5615736459467, 801.8837268596021, 850.0734199722209, 858.3862538285156, 876.373393023991, 876.513534988075, 6876.587404755631, 8339.583020848933, 8405.41162118658, 8804.647814087493, 8958.417702849294, 712.4549719464613, 712.786164150763, 729.8029532026324, 752.0636807174993, 753.1903204055662, 4925.5074492551175, 4928.186849169847, 5110.105731497048, 5124.948750512447, 5285.63000589955, 16660.50018499447, 16664.250108745135, 20312.593748124953, 20403.591928161353, 20413.387598371777, 15977.440789572309, 20337.389878303675, 20487.795122048887, 20507.0, 20524.0, 9180.21968132703, 9482.95258523703, 10211.897881021243, 10264.973502649675, 10340.0, 7723.34351580122, 8487.193716596914, 8792.560371981248, 8883.955580222058, 9012.188902998761, 17877.553061173574, 18357.54106147357, 18468.63062738711, 18482.445526634223, 18769.530761731065, 17773.298668925858, 18455.354062607745, 18637.0, 18779.0, 18965.715514267573, 11413.372264525531, 11971.461284242056, 12111.81832272499, 12178.269303841444, 12197.146199765932, 13594.660133496345, 13736.93131534376, 13739.931300343434, 13747.312634368442, 13788.241646709528, 9301.627934882528, 10232.283740138118, 10412.635557755442, 10441.947790261, 10516.842247366449, 10436.58253669875, 10505.9474702626, 10711.94644026775, 10772.299800512941, 10910.181736369645, 9813.362131461432, 9879.0, 9892.406455612687, 9924.603015879284, 9956.153726933002, 6195.907061393992, 7056.08270924797, 7200.135983681992, 7477.738279160203, 7505.66224519887, 6339.746410143543, 7388.187299397169, 7488.775336739906, 7698.653560589899, 7756.68973241064], "value": 6658.627633435694, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.7922122054091, 425.40443379269027, 460.73968207962497, 475.1589686255328, 505.67513115637036, 6404.743810247591, 6540.655564102819, 6568.142857856829, 6574.342565743424, 6586.769463068794, 5254.605904557164, 6161.47627451666, 6329.670769138486, 6330.366963303667, 6455.0, 4076.653484453825, 4721.3390125382475, 4724.393706139912, 4862.538058884409, 4866.780994855222, 4818.132736107499, 4888.584470320027, 4892.804287828482, 4926.876828079291, 4928.581070608984, 4466.754328511931, 4579.954200457986, 4839.830605928793, 4851.126797176506, 4871.098846713369, 4772.665913386064, 4776.952230477686, 4795.640326975474, 4799.832005879777, 4836.9116948686615, 4187.392828039917, 4189.790510474464, 4200.537940826506, 4209.147974891069, 4237.982884107822, 6132.63204207749, 6159.1993040904845, 6175.62946223228, 6182.045199150955, 6199.287081985539, 4971.676841005323, 5623.7908849597125, 5681.659100453986, 5745.7017193122665, 5798.478136967693, 5693.686847223401, 6631.237407698129, 6833.965830170867, 6871.0, 6902.964555316693, 4719.0, 4725.983913458623, 4899.608031357483, 4908.877278068041, 4913.361263035818, 7300.211448195173, 7351.059576169575, 7366.668499917517, 7373.29953654402, 7384.446166537531, 4736.64475164364, 4994.80020799164, 5002.074616195942, 5006.574441172541, 5024.773885175176, 4459.576340247671, 4716.64625153108, 4731.219348807477, 5137.8201762938115, 5140.254663073833, 5055.721935293521, 5618.269624948731, 5779.133130030445, 5820.031783790213, 5828.996133982006, 5773.249477567973, 6483.448906842971, 6885.139357580302, 6956.130483689539, 6963.825904352431, 1487.9479218227527, 1498.587888330696, 1527.8624923756915, 1754.3859649122705, 1759.9736003959952, 980.367662857453, 1148.724306166514, 1176.9058475321954, 1191.7724743514195, 1199.3343694249775, 5973.850653733692, 6321.778737744157, 6328.6202827830475, 6345.33373995733, 6464.709088091047, 5970.373110823388, 7040.401565866856, 7096.793545097243, 7139.89290160648, 7171.497995140395, 5976.701164941735, 6476.0, 6488.902666460007, 6490.740370385131, 6500.512461565307, 3641.2353405785084, 3656.933832384694, 3770.4344348347418, 3773.3019391412677, 3779.4519794629623, 6552.0, 7363.097558294113, 7383.926160738324, 7390.9260907390235, 7397.926020739831, 7309.195988441267, 7425.1832298447125, 7432.702691892372, 7471.0, 7480.47636665439, 6649.667516624149, 6826.0237787309525, 7364.410847132217, 7365.447591430557, 7365.8158546036775, 7125.501214914906, 7167.133146741346, 7241.493095483476, 7248.202697703354, 7256.4194864409665, 6691.43122834545, 7031.0, 7031.558530501133, 7045.542039767399, 7052.471064670068, 6106.297775755845, 7064.011038454518, 7432.0, 7432.814179645552, 7456.7390141344795, 4075.899507132998, 4640.767961601974, 4989.700617962933, 5009.799608015638, 5019.272205530251, 5690.715464226855, 7125.465590080868, 7136.143662760502, 7175.0, 7195.0, 6393.136926515047, 6854.931450685429, 6962.1297337832775, 6965.164180298397, 6966.5471744336455, 6543.5419520633095, 6893.310668933066, 6893.310668933267, 6901.137357830271, 6918.654067296515, 6623.006549017493, 6686.398224159849, 6732.932670673426, 6736.461083113437, 6800.659967001531, 6026.131899111289, 6811.829704257235, 6881.208661004049, 6912.170539535288, 6919.273476284918, 5577.382559057828, 5715.51418129447, 6446.29090800021, 6564.6389448578875, 6585.045168450643], "value": 5102.6723804561225, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [820.1224689582148, 870.4951128345552, 892.504659913748, 893.4371346051983, 895.3777124898198, 15823.920880395583, 17006.40477583285, 17536.64926701467, 17692.46922592321, 17733.46799596011, 14776.556703298891, 14836.332365043572, 14924.179170145637, 17031.74452383212, 18858.62282754344, 1038.4548112241096, 1045.7751583409565, 1054.69413869978, 1062.0600768320053, 1064.5156453813497, 1049.490996866519, 1051.4690081508827, 1053.399562249518, 1056.0917610854658, 1065.4938904020605, 1104.817705078665, 1109.1514991031868, 1110.7722916802036, 1112.3326004397377, 1114.8717897441818, 1046.9162467002623, 1081.4106312059946, 1085.2457542008308, 1099.8625171853519, 1104.0670633314837, 814.8329592433537, 817.0154963269306, 818.9262966332999, 819.5943008210974, 830.9252167304911, 17716.20277087534, 17720.822791772047, 17783.822161778477, 17828.643427131385, 17935.282588696435, 17294.65410691792, 17678.55803604907, 17765.555861103447, 17948.192331345123, 17949.371771988048, 14859.405623775036, 14955.626109347244, 15088.321025553876, 15128.773068403983, 15164.09015459076, 3563.251717139404, 3588.946165807489, 3590.0486371111674, 3602.3875941090078, 3661.6155303692994, 117064.0, 144255.1148977025, 160076.0, 161557.92499827605, 171592.0, 11939.701507462209, 12116.151869369236, 13957.79063314051, 14061.43754249839, 14274.500392486214, 26468.735312646626, 26656.866715666296, 26746.0, 26771.86614066917, 27931.58102628462, 7062.71749130029, 8044.557549334842, 8076.798080048045, 8170.632321545426, 8217.630206640715, 4103.835846566104, 4144.689148313828, 4185.644220241253, 4189.664826813848, 4191.958080419156, 4875.7074575525585, 4903.313536104948, 4999.575036121971, 5004.3494345734825, 5013.24801279811, 1023.4831410137981, 1039.365986748083, 1055.37205362809, 1055.8363453664695, 1056.8626078609884, 581.3809038733932, 655.4003087175181, 664.1366223908996, 666.5800545656302, 702.4696354252507, 11772.646820595395, 12039.916407523366, 12401.751964960651, 12533.0, 12754.61736147917, 598.937111603284, 638.6211362674002, 660.3495556876472, 687.8796210663114, 693.6462404173908, 5151.22731590257, 5358.812441564526, 5692.658440493584, 5729.9713501432225, 6018.969905150446, 130100.3494982519, 131218.34390827984, 132647.0, 134574.0, 137382.0, 91571.04788188747, 126776.0, 130861.69138308495, 130922.34538827435, 130965.72585685244, 110176.4491177555, 111004.44497777459, 112390.43804781088, 112495.0, 115126.0, 11218.0, 11864.940675296568, 12313.0, 12369.381530923598, 12718.618441446772, 44547.44083957046, 45360.0, 48901.0, 50563.0, 51137.48862511476, 52252.47747522628, 64322.07033788994, 88885.55557222173, 91445.0, 91604.54197728969, 17306.394276200273, 17615.559611009827, 17670.967838698714, 18036.63926721432, 18127.184276707318, 19262.22951081941, 19328.4201473956, 19330.936798476218, 19787.60424791525, 19905.0, 17038.74441883348, 17999.2800287987, 18267.360642377454, 18408.723869141704, 18639.90680046591, 17765.200565974304, 18286.908565457088, 18347.633047339245, 18432.539186520444, 18594.721079184095, 17450.91274543619, 17493.737593936356, 17817.1982260796, 18125.456236312933, 18154.0, 12083.879161208275, 12121.484186007101, 13298.268595227355, 13432.261225632685, 13433.395497202453, 13188.010899182791, 14297.28513574338, 14559.85440145585, 14637.0, 14704.338304776096], "value": 9134.090601380498, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1544.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 114.0, 116.0, 118.0, 129.0, 166.0, 99.0, 166.0, 167.0, 165.0], "value": 134.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 118.0, 143.0, 145.0, 142.0, 116.0, 138.0, 181.0, 148.0, 108.0], "value": 138.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 199.0, 202.0, 217.0, 223.0, 219.0, 216.0, 202.0, 233.0, 224.0], "value": 213.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 86.0, 76.0, 136.0, 56.0, 58.0, 64.0, 63.0, 103.0, 61.0], "value": 77.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 111.0, 69.0, 71.0, 75.0, 123.0, 97.0, 75.0, 105.0], "value": 86.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 88.0, 88.0, 89.0, 87.0, 88.0, 88.0, 90.0, 95.0], "value": 88.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 98.0, 96.0, 96.0, 95.0, 98.0, 96.0, 96.0], "value": 96.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 92.0, 91.0, 93.0, 132.0, 96.0, 139.0, 149.0], "value": 107.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 49.0, 54.0, 53.0, 55.0, 51.0, 53.0, 52.0, 52.0, 52.0], "value": 52.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 58.0, 59.0, 52.0, 56.0, 56.0, 52.0, 54.0, 53.0, 53.0], "value": 54.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 81.0, 82.0, 81.0, 80.0, 83.0, 81.0, 83.0, 83.0, 82.0], "value": 82.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 136.0, 140.0, 156.0, 146.0, 155.0, 150.0, 157.0, 138.0, 171.0], "value": 148.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 222.0, 223.0, 186.0, 185.0, 182.0, 206.0, 220.0, 193.0, 183.0], "value": 198.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 64.0, 63.0, 65.0, 65.0, 63.0, 66.0, 70.0, 63.0], "value": 65.3, "unit": "score"}]}]} 11:43:01 INFO - Return code: 0 11:43:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:43:01 INFO - # TBPL SUCCESS # 11:43:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:43:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:43:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:43:01 INFO - 2017-06-21 11:43:01.824 screenresolution[2133:15441] starting screenresolution argv=screenresolution get 11:43:01 INFO - 2017-06-21 11:43:01.826 screenresolution[2133:15441] Display 0: 1600x1200x32@60 11:43:01 INFO - 2017-06-21 11:43:01.833 screenresolution[2134:15444] starting screenresolution argv=screenresolution list 11:43:01 INFO - Available Modes on Display 0 11:43:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:43:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:43:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:43:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:43:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:43:02 INFO - Intel Iris: 11:43:02 INFO - Chipset Model: Intel Iris 11:43:02 INFO - Type: GPU 11:43:02 INFO - Bus: Built-In 11:43:02 INFO - VRAM (Dynamic, Max): 1536 MB 11:43:02 INFO - Vendor: Intel (0x8086) 11:43:02 INFO - Device ID: 0x0a2e 11:43:02 INFO - Revision ID: 0x0009 11:43:02 INFO - Displays: 11:43:02 INFO - Display: 11:43:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:43:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:43:02 INFO - Display Serial Number: 1600x1200 60 11:43:02 INFO - Main Display: Yes 11:43:02 INFO - Mirror: Off 11:43:02 INFO - Online: Yes 11:43:02 INFO - Rotation: Supported 11:43:02 INFO - Return code: 0 11:43:02 INFO - Running post-action listener: _resource_record_post_action 11:43:02 INFO - [mozharness: 2017-06-21 18:43:02.081203Z] Finished run-tests step (success) 11:43:02 INFO - Running post-run listener: _resource_record_post_run 11:43:02 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 4002304; Write bytes: 577650688; Read time: 53; Write time: 1740 11:43:02 INFO - TinderboxPrint: CPU usage
54.6% 11:43:02 INFO - TinderboxPrint: I/O read bytes / time
4,002,304 / 53 11:43:02 INFO - TinderboxPrint: I/O write bytes / time
577,650,688 / 1,740 11:43:02 INFO - TinderboxPrint: CPU idle
2,180.9 (45.4%) 11:43:02 INFO - TinderboxPrint: CPU system
972.5 (20.3%) 11:43:02 INFO - TinderboxPrint: CPU user
1,648.0 (34.3%) 11:43:02 INFO - TinderboxPrint: Swap in / out
258,686,976 / 0 11:43:02 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 232239616; Write bytes: 229165056; Read time: 16574; Write time: 488 11:43:02 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:43:02 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 4002304; Write bytes: 319109120; Read time: 53; Write time: 1184 11:43:02 INFO - Running post-run listener: _upload_blobber_files 11:43:02 INFO - Blob upload gear active. 11:43:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:43:02 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 11:43:02 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'] 11:43:02 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 11:43:02 INFO - (blobuploader) - INFO - Open directory for files ... 11:43:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:43:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:43:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:43:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:43:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:43:03 INFO - (blobuploader) - INFO - Done attempting. 11:43:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:43:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:43:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:43:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:43:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:43:04 INFO - (blobuploader) - INFO - Done attempting. 11:43:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:43:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:43:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:43:05 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:43:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:43:05 INFO - (blobuploader) - INFO - Done attempting. 11:43:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:43:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:43:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:43:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:43:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:43:07 INFO - (blobuploader) - INFO - Done attempting. 11:43:07 INFO - (blobuploader) - INFO - Iteration through files over. 11:43:07 INFO - Return code: 0 11:43:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:43:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:43:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/8c5bf3f783b0b1aa4b36f9b9465a2b7216ca4296cdf41f7b087310214818d465a18b6023e94615be25456022be6d6cd7a82a9787ecea4e3d4a21b6254449e54d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/3f8ed09f480a5a598557950d40b7104a6d07bc52a75f99ec52f3191dd146a1d31312d9b9e1d6111c94ce0f7b1690a6b05de08908a3b8a0b60abc80436b3d3e2e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ac39cabf98af2a4bd389e20c9c35a23534037eb4b13056f5b21968660138a40bc18d71f3e9520868c209cdf1c3798c3739dcc02f01ee3f654b8e3498582092bd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0326e940bc2f56ce73cfb0105877987055b6aec78733b13bfe089f850f6ba80fcc9290a0c73bdf00d27e737a1f32ff63c0e520193e4d4ada4caae88de504c10a"} 11:43:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:43:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:43:07 INFO - Contents: 11:43:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/8c5bf3f783b0b1aa4b36f9b9465a2b7216ca4296cdf41f7b087310214818d465a18b6023e94615be25456022be6d6cd7a82a9787ecea4e3d4a21b6254449e54d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/3f8ed09f480a5a598557950d40b7104a6d07bc52a75f99ec52f3191dd146a1d31312d9b9e1d6111c94ce0f7b1690a6b05de08908a3b8a0b60abc80436b3d3e2e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ac39cabf98af2a4bd389e20c9c35a23534037eb4b13056f5b21968660138a40bc18d71f3e9520868c209cdf1c3798c3739dcc02f01ee3f654b8e3498582092bd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0326e940bc2f56ce73cfb0105877987055b6aec78733b13bfe089f850f6ba80fcc9290a0c73bdf00d27e737a1f32ff63c0e520193e4d4ada4caae88de504c10a"} 11:43:07 INFO - Running post-run listener: copy_logs_to_upload_dir 11:43:07 INFO - Copying logs to upload dir... 11:43:07 INFO - mkdir: /builds/slave/test/build/upload/logs 11:43:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.705889 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-06-21 11:43:07.682734) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-21 11:43:07.686529) ========= 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.sdILS5Oz7v/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.96pentjAnU/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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/8c5bf3f783b0b1aa4b36f9b9465a2b7216ca4296cdf41f7b087310214818d465a18b6023e94615be25456022be6d6cd7a82a9787ecea4e3d4a21b6254449e54d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/3f8ed09f480a5a598557950d40b7104a6d07bc52a75f99ec52f3191dd146a1d31312d9b9e1d6111c94ce0f7b1690a6b05de08908a3b8a0b60abc80436b3d3e2e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ac39cabf98af2a4bd389e20c9c35a23534037eb4b13056f5b21968660138a40bc18d71f3e9520868c209cdf1c3798c3739dcc02f01ee3f654b8e3498582092bd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0326e940bc2f56ce73cfb0105877987055b6aec78733b13bfe089f850f6ba80fcc9290a0c73bdf00d27e737a1f32ff63c0e520193e4d4ada4caae88de504c10a"} 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.011023 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/8c5bf3f783b0b1aa4b36f9b9465a2b7216ca4296cdf41f7b087310214818d465a18b6023e94615be25456022be6d6cd7a82a9787ecea4e3d4a21b6254449e54d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/3f8ed09f480a5a598557950d40b7104a6d07bc52a75f99ec52f3191dd146a1d31312d9b9e1d6111c94ce0f7b1690a6b05de08908a3b8a0b60abc80436b3d3e2e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ac39cabf98af2a4bd389e20c9c35a23534037eb4b13056f5b21968660138a40bc18d71f3e9520868c209cdf1c3798c3739dcc02f01ee3f654b8e3498582092bd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0326e940bc2f56ce73cfb0105877987055b6aec78733b13bfe089f850f6ba80fcc9290a0c73bdf00d27e737a1f32ff63c0e520193e4d4ada4caae88de504c10a"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-21 11:43:07.726116) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 11:43:07.726722) ========= 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.sdILS5Oz7v/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.96pentjAnU/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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.005507 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 11:43:07.777038) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-21 11:43:07.777350) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-21 11:43:07.777622) ========= ========= Total master_lag: 0.18 =========