builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0066 starttime: 1493425196.95 results: success (0) buildid: 20170428170353 builduid: bc20d20effc0482f964be2adc15b2ca5 revision: b3b32894d9326d147238d9f071e1814ab3fdc850 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-28 17:19:56.951263) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-28 17:19:56.951739) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-28 17:19:56.952026) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-28 17:19:56.976021) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:19:56.976370) ========= 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.8EN5Xi1oDJ/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.oxdhcLXHA9/Listeners TMPDIR=/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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-04-28 17:19:57-- 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% 76.4M=0s 2017-04-28 17:19:57 (76.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.132714 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:19:57.134513) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:19:57.134834) ========= 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.8EN5Xi1oDJ/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.oxdhcLXHA9/Listeners TMPDIR=/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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.041005 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:19:57.198234) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-28 17:19:57.198618) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b3b32894d9326d147238d9f071e1814ab3fdc850 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev b3b32894d9326d147238d9f071e1814ab3fdc850 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8EN5Xi1oDJ/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.oxdhcLXHA9/Listeners TMPDIR=/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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-04-28 17:19:57,334 truncating revision to first 12 chars 2017-04-28 17:19:57,334 Setting DEBUG logging. 2017-04-28 17:19:57,334 attempt 1/10 2017-04-28 17:19:57,334 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b3b32894d932?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-28 17:19:58,574 unpacking tar archive at: mozilla-central-b3b32894d932/testing/mozharness/ program finished with exit code 0 elapsedTime=1.528766 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-28 17:19:58.754418) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-28 17:19:58.754924) ========= script_repo_revision: b3b32894d9326d147238d9f071e1814ab3fdc850 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-28 17:19:58.755580) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-28 17:19:58.756066) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-28 17:19:58.778111) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-04-28 17:19:58.778657) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8EN5Xi1oDJ/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.oxdhcLXHA9/Listeners TMPDIR=/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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 17:19:59 INFO - MultiFileLogger online at 20170428 17:19:59 in /builds/slave/test 17:19:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 17:19:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:19:59 INFO - {'append_to_log': False, 17:19:59 INFO - 'base_work_dir': '/builds/slave/test', 17:19:59 INFO - 'blob_upload_branch': 'Firefox', 17:19:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:19:59 INFO - 'branch': 'Firefox', 17:19:59 INFO - 'buildbot_json_path': 'buildprops.json', 17:19:59 INFO - 'config_files': ('talos/mac_config.py',), 17:19:59 INFO - 'default_actions': ('clobber', 17:19:59 INFO - 'read-buildbot-config', 17:19:59 INFO - 'download-and-extract', 17:19:59 INFO - 'populate-webroot', 17:19:59 INFO - 'create-virtualenv', 17:19:59 INFO - 'install', 17:19:59 INFO - 'run-tests'), 17:19:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:19:59 INFO - 'download_minidump_stackwalk': True, 17:19:59 INFO - 'download_symbols': 'ondemand', 17:19:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:19:59 INFO - 'tooltool.py': '/tools/tooltool.py', 17:19:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:19:59 INFO - '/tools/misc-python/virtualenv.py')}, 17:19:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:19:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:19:59 INFO - 'gecko_profile': False, 17:19:59 INFO - 'gecko_profile_interval': 0, 17:19:59 INFO - 'installer_path': 'installer.exe', 17:19:59 INFO - 'log_level': 'info', 17:19:59 INFO - 'log_name': 'talos', 17:19:59 INFO - 'log_to_console': True, 17:19:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:19:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:19:59 INFO - 'opt_config_files': (), 17:19:59 INFO - 'pip_index': False, 17:19:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:19:59 INFO - 'cmd': ('bash', 17:19:59 INFO - '-c', 17:19:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:19:59 INFO - 'enabled': True, 17:19:59 INFO - 'halt_on_failure': False, 17:19:59 INFO - 'name': 'check_screen_resolution'},), 17:19:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:19:59 INFO - 'cmd': ('bash', 17:19:59 INFO - '-c', 17:19:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:19:59 INFO - 'enabled': True, 17:19:59 INFO - 'halt_on_failure': False, 17:19:59 INFO - 'name': 'check_screen_resolution'},), 17:19:59 INFO - 'run_cmd_checks_enabled': True, 17:19:59 INFO - 'suite': 'dromaeojs-e10s', 17:19:59 INFO - 'system_bits': '32', 17:19:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:19:59 INFO - 'title': 't-yosemite-r7-0066', 17:19:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:19:59 INFO - 'use_talos_json': True, 17:19:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:19:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:19:59 INFO - 'work_dir': 'build'} 17:19:59 INFO - [mozharness: 2017-04-29 00:19:59.018073Z] Running clobber step. 17:19:59 INFO - Running pre-action listener: _resource_record_pre_action 17:19:59 INFO - Running main action method: clobber 17:19:59 INFO - rmtree: /builds/slave/test/build 17:19:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:20:01 INFO - Running post-action listener: _resource_record_post_action 17:20:01 INFO - [mozharness: 2017-04-29 00:20:01.608623Z] Finished clobber step (success) 17:20:01 INFO - [mozharness: 2017-04-29 00:20:01.608753Z] Running read-buildbot-config step. 17:20:01 INFO - Running pre-action listener: _resource_record_pre_action 17:20:01 INFO - Running main action method: read_buildbot_config 17:20:01 INFO - Using buildbot properties: 17:20:01 INFO - { 17:20:01 INFO - "project": "", 17:20:01 INFO - "product": "firefox", 17:20:01 INFO - "script_repo_revision": "production", 17:20:01 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 17:20:01 INFO - "repository": "", 17:20:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 17:20:01 INFO - "buildid": "20170428170353", 17:20:01 INFO - "stage_platform": "macosx64", 17:20:01 INFO - "basedir": "/builds/slave/test", 17:20:01 INFO - "buildnumber": 130, 17:20:01 INFO - "platform": "yosemite_r7", 17:20:01 INFO - "mozci_request": { 17:20:01 INFO - "priority": 0, 17:20:01 INFO - "type": "trigger_all_talos_jobs", 17:20:01 INFO - "times": 6 17:20:01 INFO - }, 17:20:01 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 17:20:01 INFO - "slavebuilddir": "test", 17:20:01 INFO - "branch": "mozilla-central", 17:20:01 INFO - "revision": "b3b32894d9326d147238d9f071e1814ab3fdc850", 17:20:01 INFO - "slavename": "t-yosemite-r7-0066", 17:20:01 INFO - "builduid": "bc20d20effc0482f964be2adc15b2ca5", 17:20:01 INFO - "repo_path": "mozilla-central" 17:20:01 INFO - } 17:20:01 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg. 17:20:01 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.test_packages.json. 17:20:01 INFO - Running post-action listener: _resource_record_post_action 17:20:01 INFO - [mozharness: 2017-04-29 00:20:01.612583Z] Finished read-buildbot-config step (success) 17:20:01 INFO - [mozharness: 2017-04-29 00:20:01.612693Z] Running download-and-extract step. 17:20:01 INFO - Running pre-action listener: _resource_record_pre_action 17:20:01 INFO - Running main action method: download_and_extract 17:20:01 INFO - mkdir: /builds/slave/test/build/tests 17:20:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 17:20:01 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.test_packages.json 17:20:01 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:20:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 17:20:01 INFO - Downloaded 1325 bytes. 17:20:01 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:20:01 INFO - Using the following test package requirements: 17:20:01 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:20:01 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 17:20:01 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:20:01 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:20:01 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 17:20:01 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:20:01 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 17:20:01 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:20:01 INFO - u'jsshell-mac.zip'], 17:20:01 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:20:01 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 17:20:01 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:20:01 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:20:01 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 17:20:01 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:20:01 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 17:20:01 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:20:01 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 17:20:01 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:20:01 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 17:20:01 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:20:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.common.tests.zip 17:20:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:20:01 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:20:03 INFO - Content-Length response header: 52698163 17:20:03 INFO - Bytes received: 52698163 17:20:19 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 17:20:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.common.tests.zip 17:20:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:20:19 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:20:20 INFO - Content-Length response header: 52698163 17:20:20 INFO - Bytes received: 52698163 17:20:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.talos.tests.zip 17:20:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:20:36 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 17:20:36 INFO - Content-Length response header: 14408809 17:20:36 INFO - Bytes received: 14408809 17:20:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 17:20:37 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg 17:20:37 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 17:20:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 17:20:38 INFO - Downloaded 71219323 bytes. 17:20:38 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg 17:20:38 INFO - mkdir: /builds/slave/test/properties 17:20:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:20:38 INFO - Writing to file /builds/slave/test/properties/build_url 17:20:38 INFO - Contents: 17:20:38 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg 17:20:38 INFO - Running post-action listener: _resource_record_post_action 17:20:38 INFO - Running post-action listener: set_extra_try_arguments 17:20:38 INFO - [mozharness: 2017-04-29 00:20:38.863995Z] Finished download-and-extract step (success) 17:20:38 INFO - [mozharness: 2017-04-29 00:20:38.864124Z] Running populate-webroot step. 17:20:38 INFO - Running pre-action listener: _resource_record_pre_action 17:20:38 INFO - Running main action method: populate_webroot 17:20:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:20:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:20:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:20:38 INFO - '--tppagecycles', 17:20:38 INFO - '7'], 17:20:38 INFO - 'tests': ['tsvgm']}}, 17:20:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:20:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:20:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:20:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:20:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:20:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:20:38 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:20:38 INFO - 'talos_options': ['--disable-e10s'], 17:20:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:20:38 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:20:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:20:38 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:20:38 INFO - 'talos_options': ['--disable-e10s'], 17:20:38 INFO - 'tests': ['damp', 'tps']}, 17:20:38 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:20:38 INFO - 'tests': ['damp', 'tps']}, 17:20:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:20:38 INFO - 'tests': ['dromaeo_dom']}, 17:20:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:20:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:20:38 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 17:20:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:20:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:20:38 INFO - 'tests': ['a11yr', 17:20:38 INFO - 'ts_paint', 17:20:38 INFO - 'tpaint', 17:20:38 INFO - 'sessionrestore', 17:20:38 INFO - 'sessionrestore_no_auto_restore', 17:20:38 INFO - 'tabpaint']}, 17:20:38 INFO - 'other-e10s': {'tests': ['a11yr', 17:20:38 INFO - 'ts_paint', 17:20:38 INFO - 'tpaint', 17:20:38 INFO - 'sessionrestore', 17:20:38 INFO - 'sessionrestore_no_auto_restore', 17:20:38 INFO - 'tabpaint']}, 17:20:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:20:38 INFO - 'ts_paint', 17:20:38 INFO - 'tpaint', 17:20:38 INFO - 'sessionrestore', 17:20:38 INFO - 'sessionrestore_no_auto_restore', 17:20:38 INFO - 'tabpaint']}, 17:20:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:20:38 INFO - 'ts_paint', 17:20:38 INFO - 'tpaint', 17:20:38 INFO - 'sessionrestore', 17:20:38 INFO - 'sessionrestore_no_auto_restore', 17:20:38 INFO - 'tabpaint']}, 17:20:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:20:38 INFO - 'tests': ['a11yr', 17:20:38 INFO - 'ts_paint', 17:20:38 INFO - 'tpaint', 17:20:38 INFO - 'sessionrestore', 17:20:38 INFO - 'sessionrestore_no_auto_restore', 17:20:38 INFO - 'tabpaint']}, 17:20:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:20:38 INFO - 'tests': ['a11yr', 17:20:38 INFO - 'ts_paint', 17:20:38 INFO - 'tpaint', 17:20:38 INFO - 'sessionrestore', 17:20:38 INFO - 'sessionrestore_no_auto_restore', 17:20:38 INFO - 'tabpaint']}, 17:20:38 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 17:20:38 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 17:20:38 INFO - 'bloom_basic_ref']}, 17:20:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:20:38 INFO - 'tests': ['tsvgx', 17:20:38 INFO - 'tsvgr_opacity', 17:20:38 INFO - 'tart', 17:20:38 INFO - 'tscrollx', 17:20:38 INFO - 'cart', 17:20:38 INFO - 'tsvg_static']}, 17:20:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:20:38 INFO - 'tsvgr_opacity', 17:20:38 INFO - 'tart', 17:20:38 INFO - 'tscrollx', 17:20:38 INFO - 'cart', 17:20:38 INFO - 'tsvg_static']}, 17:20:38 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:20:38 INFO - 'talos_options': ['--disable-e10s'], 17:20:38 INFO - 'tests': ['tp5o']}, 17:20:38 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:20:38 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:20:38 INFO - 'talos_options': ['--disable-e10s', 17:20:38 INFO - '--xperf_path', 17:20:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:20:38 INFO - 'tests': ['tp5n']}, 17:20:38 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:20:38 INFO - 'talos_options': ['--xperf_path', 17:20:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:20:38 INFO - 'tests': ['tp5n']}}, 17:20:38 INFO - 'talos.zip': {'path': '', 17:20:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:20:38 INFO - Running post-action listener: _resource_record_post_action 17:20:38 INFO - [mozharness: 2017-04-29 00:20:38.883436Z] Finished populate-webroot step (success) 17:20:38 INFO - [mozharness: 2017-04-29 00:20:38.883575Z] Running create-virtualenv step. 17:20:38 INFO - Running pre-action listener: _resource_record_pre_action 17:20:38 INFO - Running main action method: create_virtualenv 17:20:38 INFO - Creating virtualenv /builds/slave/test/build/venv 17:20:38 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 17:20:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:20:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:20:39 INFO - Using real prefix '/tools/python27' 17:20:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:20:39 INFO - Installing distribute.............................................................................................................................................................................................done. 17:20:42 INFO - Installing pip.................done. 17:20:42 INFO - Return code: 0 17:20:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:20:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:20:43 INFO - Reading from file tmpfile_stdout 17:20:43 INFO - Output received: 17:20:43 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) 17:20:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:20:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 17:20:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 17:20:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10c92cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c92f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9e84d3b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c70ad30>, '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 0x10c630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ffbe8>, '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.8EN5Xi1oDJ/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.oxdhcLXHA9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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 17:20:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 17:20:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:20:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8EN5Xi1oDJ/Render', 17:20:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:20:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:20:43 INFO - 'HOME': '/Users/cltbld', 17:20:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:20:43 INFO - 'LOGNAME': 'cltbld', 17:20:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:43 INFO - 'MOZ_NO_REMOTE': '1', 17:20:43 INFO - 'NO_EM_RESTART': '1', 17:20:43 INFO - 'PAGER': '/bin/cat', 17:20:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:20:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:43 INFO - 'PWD': '/builds/slave/test', 17:20:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:20:43 INFO - 'SHELL': '/bin/bash', 17:20:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oxdhcLXHA9/Listeners', 17:20:43 INFO - 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/', 17:20:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:20:43 INFO - 'USER': 'cltbld', 17:20:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:20:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:20:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:43 INFO - 'XPC_FLAGS': '0x0', 17:20:43 INFO - 'XPC_SERVICE_NAME': '0', 17:20:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:20:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:43 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 17:20:43 INFO - Cleaning up... 17:20:43 INFO - Return code: 0 17:20:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:20:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 17:20:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 17:20:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10c92cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c92f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9e84d3b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c70ad30>, '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 0x10c630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ffbe8>, '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.8EN5Xi1oDJ/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.oxdhcLXHA9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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 17:20:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 17:20:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:20:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8EN5Xi1oDJ/Render', 17:20:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:20:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:20:43 INFO - 'HOME': '/Users/cltbld', 17:20:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:20:43 INFO - 'LOGNAME': 'cltbld', 17:20:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:43 INFO - 'MOZ_NO_REMOTE': '1', 17:20:43 INFO - 'NO_EM_RESTART': '1', 17:20:43 INFO - 'PAGER': '/bin/cat', 17:20:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:20:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:43 INFO - 'PWD': '/builds/slave/test', 17:20:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:20:43 INFO - 'SHELL': '/bin/bash', 17:20:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oxdhcLXHA9/Listeners', 17:20:43 INFO - 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/', 17:20:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:20:43 INFO - 'USER': 'cltbld', 17:20:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:20:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:20:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:43 INFO - 'XPC_FLAGS': '0x0', 17:20:43 INFO - 'XPC_SERVICE_NAME': '0', 17:20:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:20:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:43 INFO - Downloading/unpacking psutil>=3.1.1 17:20: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 17:20: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 17:20:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20: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 17:20: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 17:20:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:20:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:20:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:20:47 INFO - Installing collected packages: psutil 17:20:47 INFO - Running setup.py install for psutil 17:20:47 INFO - building 'psutil._psutil_osx' extension 17:20:47 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 17:20:47 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 17:20:47 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 17:20:47 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 17:20:48 INFO - building 'psutil._psutil_posix' extension 17:20:48 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 17:20:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:20:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:20:48 INFO - ^ 17:20:48 INFO - 1 warning generated. 17:20:48 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 17:20:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:20:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:20:48 INFO - Successfully installed psutil 17:20:48 INFO - Cleaning up... 17:20:48 INFO - Return code: 0 17:20:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:20:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 17:20:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 17:20:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:48 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10c92cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c92f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9e84d3b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c70ad30>, '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 0x10c630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ffbe8>, '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.8EN5Xi1oDJ/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.oxdhcLXHA9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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 17:20:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 17:20:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:20:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8EN5Xi1oDJ/Render', 17:20:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:20:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:20:48 INFO - 'HOME': '/Users/cltbld', 17:20:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:20:48 INFO - 'LOGNAME': 'cltbld', 17:20:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:48 INFO - 'MOZ_NO_REMOTE': '1', 17:20:48 INFO - 'NO_EM_RESTART': '1', 17:20:48 INFO - 'PAGER': '/bin/cat', 17:20:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:20:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:48 INFO - 'PWD': '/builds/slave/test', 17:20:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:20:48 INFO - 'SHELL': '/bin/bash', 17:20:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oxdhcLXHA9/Listeners', 17:20:48 INFO - 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/', 17:20:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:20:48 INFO - 'USER': 'cltbld', 17:20:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:20:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:20:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:48 INFO - 'XPC_FLAGS': '0x0', 17:20:48 INFO - 'XPC_SERVICE_NAME': '0', 17:20:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:20:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:20:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:20:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:20:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:20:51 INFO - Installing collected packages: mozsystemmonitor 17:20:51 INFO - Running setup.py install for mozsystemmonitor 17:20:51 INFO - Successfully installed mozsystemmonitor 17:20:51 INFO - Cleaning up... 17:20:51 INFO - Return code: 0 17:20:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:20:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 17:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 17:20:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:51 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10c92cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c92f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9e84d3b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c70ad30>, '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 0x10c630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ffbe8>, '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.8EN5Xi1oDJ/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.oxdhcLXHA9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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 17:20:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 17:20:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:20:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8EN5Xi1oDJ/Render', 17:20:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:20:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:20:51 INFO - 'HOME': '/Users/cltbld', 17:20:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:20:51 INFO - 'LOGNAME': 'cltbld', 17:20:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:51 INFO - 'MOZ_NO_REMOTE': '1', 17:20:51 INFO - 'NO_EM_RESTART': '1', 17:20:51 INFO - 'PAGER': '/bin/cat', 17:20:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:20:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:51 INFO - 'PWD': '/builds/slave/test', 17:20:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:20:51 INFO - 'SHELL': '/bin/bash', 17:20:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oxdhcLXHA9/Listeners', 17:20:51 INFO - 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/', 17:20:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:20:51 INFO - 'USER': 'cltbld', 17:20:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:20:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:20:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:51 INFO - 'XPC_FLAGS': '0x0', 17:20:51 INFO - 'XPC_SERVICE_NAME': '0', 17:20:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:20:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:51 INFO - Downloading/unpacking jsonschema==2.5.1 17:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:20:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:20:54 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 17:20:54 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 17:20:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:54 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 17:20:54 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 17:20:54 INFO - Downloading functools32-3.2.3-2.tar.gz 17:20:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:20:55 INFO - warning: no files found matching '*.txt' 17:20:55 INFO - no previously-included directories found matching 'build' 17:20:55 INFO - no previously-included directories found matching 'dist' 17:20:55 INFO - no previously-included directories found matching '.git*' 17:20:55 INFO - Installing collected packages: jsonschema, functools32 17:20:55 INFO - Running setup.py install for functools32 17:20:55 INFO - warning: no files found matching '*.txt' 17:20:55 INFO - no previously-included directories found matching 'build' 17:20:55 INFO - no previously-included directories found matching 'dist' 17:20:55 INFO - no previously-included directories found matching '.git*' 17:20:55 INFO - Successfully installed jsonschema functools32 17:20:55 INFO - Cleaning up... 17:20:55 INFO - Return code: 0 17:20:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:20:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 17:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 17:20:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:56 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10c92cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c92f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9e84d3b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c70ad30>, '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 0x10c630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ffbe8>, '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.8EN5Xi1oDJ/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.oxdhcLXHA9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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 17:20:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 17:20:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:20:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8EN5Xi1oDJ/Render', 17:20:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:20:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:20:56 INFO - 'HOME': '/Users/cltbld', 17:20:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:20:56 INFO - 'LOGNAME': 'cltbld', 17:20:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:56 INFO - 'MOZ_NO_REMOTE': '1', 17:20:56 INFO - 'NO_EM_RESTART': '1', 17:20:56 INFO - 'PAGER': '/bin/cat', 17:20:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:20:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:56 INFO - 'PWD': '/builds/slave/test', 17:20:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:20:56 INFO - 'SHELL': '/bin/bash', 17:20:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oxdhcLXHA9/Listeners', 17:20:56 INFO - 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/', 17:20:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:20:56 INFO - 'USER': 'cltbld', 17:20:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:20:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:20:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:56 INFO - 'XPC_FLAGS': '0x0', 17:20:56 INFO - 'XPC_SERVICE_NAME': '0', 17:20:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:20:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:20:56 INFO - Cleaning up... 17:20:56 INFO - Return code: 0 17:20:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:20:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 17:20:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 17:20:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:56 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10c92cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c92f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9e84d3b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c70ad30>, '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 0x10c630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ffbe8>, '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.8EN5Xi1oDJ/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.oxdhcLXHA9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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 17:20:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 17:20:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:20:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8EN5Xi1oDJ/Render', 17:20:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:20:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:20:56 INFO - 'HOME': '/Users/cltbld', 17:20:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:20:56 INFO - 'LOGNAME': 'cltbld', 17:20:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:56 INFO - 'MOZ_NO_REMOTE': '1', 17:20:56 INFO - 'NO_EM_RESTART': '1', 17:20:56 INFO - 'PAGER': '/bin/cat', 17:20:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:20:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:56 INFO - 'PWD': '/builds/slave/test', 17:20:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:20:56 INFO - 'SHELL': '/bin/bash', 17:20:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oxdhcLXHA9/Listeners', 17:20:56 INFO - 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/', 17:20:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:20:56 INFO - 'USER': 'cltbld', 17:20:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:20:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:20:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:56 INFO - 'XPC_FLAGS': '0x0', 17:20:56 INFO - 'XPC_SERVICE_NAME': '0', 17:20:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:20:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:56 INFO - Downloading/unpacking blobuploader==1.2.4 17:20: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 17:20: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 17:20:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20: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 17:20: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 17:21:00 INFO - Downloading blobuploader-1.2.4.tar.gz 17:21:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:21:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:21:00 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 17:21:00 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 17:21:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:21:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:21:00 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 17:21:00 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 17:21:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:21:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:21:00 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 17:21:00 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 17:21:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:21:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:21:00 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 17:21:00 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 17:21:01 INFO - Downloading docopt-0.6.1.tar.gz 17:21:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:21:01 INFO - Installing collected packages: blobuploader, requests, docopt 17:21:01 INFO - Running setup.py install for blobuploader 17:21:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:21:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:21:01 INFO - Running setup.py install for requests 17:21:02 INFO - Running setup.py install for docopt 17:21:02 INFO - Successfully installed blobuploader requests docopt 17:21:02 INFO - Cleaning up... 17:21:02 INFO - Return code: 0 17:21:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:21:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 17:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 17:21:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:02 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10c92cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c92f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9e84d3b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c70ad30>, '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 0x10c630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ffbe8>, '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.8EN5Xi1oDJ/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.oxdhcLXHA9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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 17:21:02 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 17:21:02 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:21:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8EN5Xi1oDJ/Render', 17:21:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:02 INFO - 'HOME': '/Users/cltbld', 17:21:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:02 INFO - 'LOGNAME': 'cltbld', 17:21:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:02 INFO - 'MOZ_NO_REMOTE': '1', 17:21:02 INFO - 'NO_EM_RESTART': '1', 17:21:02 INFO - 'PAGER': '/bin/cat', 17:21:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:02 INFO - 'PWD': '/builds/slave/test', 17:21:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:02 INFO - 'SHELL': '/bin/bash', 17:21:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oxdhcLXHA9/Listeners', 17:21:02 INFO - 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/', 17:21:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:02 INFO - 'USER': 'cltbld', 17:21:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:02 INFO - 'XPC_FLAGS': '0x0', 17:21:02 INFO - 'XPC_SERVICE_NAME': '0', 17:21:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:21:02 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-ouhyoe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:21:02 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-A7K0KR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:21:02 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-yL6AlX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:21:02 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-MFNx4V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:21:02 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-z6ygpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:21:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:21:03 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-l52aPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:21:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:21:03 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-dcIsM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:21:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:21:03 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-zpGNhi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:21:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:21:03 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-WExZK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:21:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:21:03 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-AkYvr8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:21:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:21:03 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-ImLsyW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:21:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:21:03 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-rVfjs5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:21:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:21:03 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-g_1Nqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:21:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:21:03 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-iG0qEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:21:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:21:03 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-UNM2Qq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:21:04 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-09sCVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:21:04 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-8K57J6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:21:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:21:04 INFO - Running setup.py install for manifestparser 17:21:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:21:04 INFO - Running setup.py install for mozcrash 17:21:04 INFO - Running setup.py install for mozdebug 17:21:04 INFO - Running setup.py install for mozdevice 17:21:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:21:04 INFO - Running setup.py install for mozfile 17:21:05 INFO - Running setup.py install for mozhttpd 17:21:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:21:05 INFO - Running setup.py install for mozinfo 17:21:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:21:05 INFO - Running setup.py install for mozInstall 17:21:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:21:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:21:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:21:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:21:05 INFO - Running setup.py install for mozleak 17:21:05 INFO - Running setup.py install for mozlog 17:21:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:21:05 INFO - Running setup.py install for moznetwork 17:21:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:21:05 INFO - Running setup.py install for mozprocess 17:21:06 INFO - Running setup.py install for mozprofile 17:21:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:21:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:21:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:21:06 INFO - Running setup.py install for mozrunner 17:21:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:21:06 INFO - Running setup.py install for mozscreenshot 17:21:06 INFO - Running setup.py install for moztest 17:21:06 INFO - Running setup.py install for mozversion 17:21:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:21:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:21:06 INFO - Cleaning up... 17:21:06 INFO - Return code: 0 17:21:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:21:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 17:21:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 17:21:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:06 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10c92cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c92f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9e84d3b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c70ad30>, '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 0x10c630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ffbe8>, '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.8EN5Xi1oDJ/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.oxdhcLXHA9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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 17:21:06 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 17:21:06 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:21:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8EN5Xi1oDJ/Render', 17:21:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:06 INFO - 'HOME': '/Users/cltbld', 17:21:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:06 INFO - 'LOGNAME': 'cltbld', 17:21:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:06 INFO - 'MOZ_NO_REMOTE': '1', 17:21:06 INFO - 'NO_EM_RESTART': '1', 17:21:06 INFO - 'PAGER': '/bin/cat', 17:21:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:06 INFO - 'PWD': '/builds/slave/test', 17:21:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:06 INFO - 'SHELL': '/bin/bash', 17:21:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oxdhcLXHA9/Listeners', 17:21:06 INFO - 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/', 17:21:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:06 INFO - 'USER': 'cltbld', 17:21:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:06 INFO - 'XPC_FLAGS': '0x0', 17:21:06 INFO - 'XPC_SERVICE_NAME': '0', 17:21:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:21:07 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-WswWTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:21:07 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 17:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:21:07 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-TrNwjK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:21:07 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)) 17:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:21:07 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-QXadT_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:21:07 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)) 17:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:21:07 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-Tydktz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:21:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:21:07 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-ygcwDD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:21:07 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)) 17:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:21:07 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-a2LDPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:21:07 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)) 17:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:21:07 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-m6sDhQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:21:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 17:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:21:07 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-Kbh3i0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:21:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:21:07 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-P87R8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:21:08 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-x1u8Qu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:21:08 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-EWKG_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 17:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:21:08 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-cACE2y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:21:08 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)) 17:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:21:08 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-pF24AS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:21:08 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)) 17:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:21:08 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-UABH2J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:21:08 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)) 17:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:21:08 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-eFH4Rl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:21:08 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)) 17:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:21:08 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-L73ls3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:21:08 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)) 17:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:21:08 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-SGsxwP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:21:08 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)) 17:21:08 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)) 17:21:08 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)) 17:21:08 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)) 17:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:21:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:21:08 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 17:21:08 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 17:21:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:21:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:21:08 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 17:21:08 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 17:21:11 INFO - Downloading blessings-1.6.tar.gz 17:21:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:21:11 INFO - Installing collected packages: blessings 17:21:11 INFO - Running setup.py install for blessings 17:21:11 INFO - Successfully installed blessings 17:21:11 INFO - Cleaning up... 17:21:11 INFO - Return code: 0 17:21:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:21:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:21:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:21:12 INFO - Reading from file tmpfile_stdout 17:21:12 INFO - Current package versions: 17:21:12 INFO - blessings == 1.6 17:21:12 INFO - blobuploader == 1.2.4 17:21:12 INFO - docopt == 0.6.1 17:21:12 INFO - functools32 == 3.2.3-2 17:21:12 INFO - jsonschema == 2.5.1 17:21:12 INFO - manifestparser == 1.1 17:21:12 INFO - mozInstall == 1.12 17:21:12 INFO - mozcrash == 1.0 17:21:12 INFO - mozdebug == 0.1 17:21:12 INFO - mozdevice == 0.50 17:21:12 INFO - mozfile == 1.2 17:21:12 INFO - mozhttpd == 0.7 17:21:12 INFO - mozinfo == 0.9 17:21:12 INFO - mozleak == 0.1 17:21:12 INFO - mozlog == 3.4 17:21:12 INFO - moznetwork == 0.27 17:21:12 INFO - mozprocess == 0.25 17:21:12 INFO - mozprofile == 0.28 17:21:12 INFO - mozrunner == 6.13 17:21:12 INFO - mozscreenshot == 0.1 17:21:12 INFO - mozsystemmonitor == 0.3 17:21:12 INFO - moztest == 0.8 17:21:12 INFO - mozversion == 1.4 17:21:12 INFO - psutil == 3.1.1 17:21:12 INFO - requests == 1.2.3 17:21:12 INFO - wsgiref == 0.1.2 17:21:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:21:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 17:21:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 17:21:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:12 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10c92cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c92f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9e84d3b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c70ad30>, '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 0x10c630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ffbe8>, '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.8EN5Xi1oDJ/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.oxdhcLXHA9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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 17:21:12 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 17:21:12 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:21:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8EN5Xi1oDJ/Render', 17:21:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:12 INFO - 'HOME': '/Users/cltbld', 17:21:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:12 INFO - 'LOGNAME': 'cltbld', 17:21:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:12 INFO - 'MOZ_NO_REMOTE': '1', 17:21:12 INFO - 'NO_EM_RESTART': '1', 17:21:12 INFO - 'PAGER': '/bin/cat', 17:21:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:12 INFO - 'PWD': '/builds/slave/test', 17:21:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:12 INFO - 'SHELL': '/bin/bash', 17:21:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oxdhcLXHA9/Listeners', 17:21:12 INFO - 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/', 17:21:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:12 INFO - 'USER': 'cltbld', 17:21:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:12 INFO - 'XPC_FLAGS': '0x0', 17:21:12 INFO - 'XPC_SERVICE_NAME': '0', 17:21:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:12 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)) 17:21:12 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)) 17:21:12 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)) 17:21:12 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)) 17:21:12 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)) 17:21:12 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)) 17:21:12 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)) 17:21:12 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)) 17:21:12 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)) 17:21:12 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)) 17:21:12 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)) 17:21:12 INFO - Cleaning up... 17:21:12 INFO - Return code: 0 17:21:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:21:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 17:21:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 17:21:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:12 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10c92cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c92f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9e84d3b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c70ad30>, '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 0x10c630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ffbe8>, '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.8EN5Xi1oDJ/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.oxdhcLXHA9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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 17:21:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:21:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:21:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8EN5Xi1oDJ/Render', 17:21:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:12 INFO - 'HOME': '/Users/cltbld', 17:21:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:12 INFO - 'LOGNAME': 'cltbld', 17:21:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:12 INFO - 'MOZ_NO_REMOTE': '1', 17:21:12 INFO - 'NO_EM_RESTART': '1', 17:21:12 INFO - 'PAGER': '/bin/cat', 17:21:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:12 INFO - 'PWD': '/builds/slave/test', 17:21:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:12 INFO - 'SHELL': '/bin/bash', 17:21:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oxdhcLXHA9/Listeners', 17:21:12 INFO - 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/', 17:21:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:12 INFO - 'USER': 'cltbld', 17:21:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:12 INFO - 'XPC_FLAGS': '0x0', 17:21:12 INFO - 'XPC_SERVICE_NAME': '0', 17:21:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:21:12 INFO - Cleaning up... 17:21:12 INFO - Return code: 0 17:21:12 INFO - Running post-action listener: _resource_record_post_action 17:21:12 INFO - Running post-action listener: _start_resource_monitoring 17:21:12 INFO - Starting resource monitoring. 17:21:12 INFO - [mozharness: 2017-04-29 00:21:12.720242Z] Finished create-virtualenv step (success) 17:21:12 INFO - [mozharness: 2017-04-29 00:21:12.721181Z] Running install step. 17:21:12 INFO - Running pre-action listener: _resource_record_pre_action 17:21:12 INFO - Running main action method: install 17:21:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:21:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:21:12 INFO - Reading from file tmpfile_stdout 17:21:12 INFO - Detecting whether we're running mozinstall >=1.0... 17:21:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:21:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:21:13 INFO - Reading from file tmpfile_stdout 17:21:13 INFO - Output received: 17:21:13 INFO - Usage: mozinstall [options] installer 17:21:13 INFO - Options: 17:21:13 INFO - -h, --help show this help message and exit 17:21:13 INFO - -d DEST, --destination=DEST 17:21:13 INFO - Directory to install application into. [default: 17:21:13 INFO - "/builds/slave/test"] 17:21:13 INFO - --app=APP Application being installed. [default: firefox] 17:21:13 INFO - mkdir: /builds/slave/test/build/application 17:21:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:21:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 17:21:32 INFO - Reading from file tmpfile_stdout 17:21:32 INFO - Output received: 17:21:32 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:21:32 INFO - Running post-action listener: _resource_record_post_action 17:21:32 INFO - [mozharness: 2017-04-29 00:21:32.660512Z] Finished install step (success) 17:21:32 INFO - [mozharness: 2017-04-29 00:21:32.660645Z] Running run-tests step. 17:21:32 INFO - Running pre-action listener: _resource_record_pre_action 17:21:32 INFO - Running main action method: run_tests 17:21:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:21:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:21:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:21:32 INFO - 2017-04-28 17:21:32.675 screenresolution[1621:7436] starting screenresolution argv=screenresolution get 17:21:32 INFO - 2017-04-28 17:21:32.677 screenresolution[1621:7436] Display 0: 1600x1200x32@60 17:21:32 INFO - 2017-04-28 17:21:32.683 screenresolution[1622:7439] starting screenresolution argv=screenresolution list 17:21:32 INFO - Available Modes on Display 0 17:21:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:21:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:21:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:21:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:21:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:21:32 INFO - Intel Iris: 17:21:32 INFO - Chipset Model: Intel Iris 17:21:32 INFO - Type: GPU 17:21:32 INFO - Bus: Built-In 17:21:32 INFO - VRAM (Dynamic, Max): 1536 MB 17:21:32 INFO - Vendor: Intel (0x8086) 17:21:32 INFO - Device ID: 0x0a2e 17:21:32 INFO - Revision ID: 0x0009 17:21:32 INFO - Displays: 17:21:32 INFO - Display: 17:21:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:21:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:21:32 INFO - Display Serial Number: 1600x1200 60 17:21:32 INFO - Main Display: Yes 17:21:32 INFO - Mirror: Off 17:21:32 INFO - Online: Yes 17:21:32 INFO - Rotation: Supported 17:21:32 INFO - Return code: 0 17:21:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:21:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:21:32 INFO - Python 2.7.3 17:21:32 INFO - Return code: 0 17:21:32 INFO - grabbing minidump binary from tooltool 17:21:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 17:21:32 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 0x10c70ad30>, '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 0x10c630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ffbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:21:32 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 17:21:32 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 17:21:32 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 17:21:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:21:33 INFO - Return code: 0 17:21:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:21:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:21:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:21:33 INFO - ENV: RUST_BACKTRACE is now 1 17:21:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:21:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:21:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0066', '--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 17:21:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0066 --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 17:21:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8EN5Xi1oDJ/Render', 17:21:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:33 INFO - 'HOME': '/Users/cltbld', 17:21:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:33 INFO - 'LOGNAME': 'cltbld', 17:21:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:21:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:21:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:33 INFO - 'MOZ_NO_REMOTE': '1', 17:21:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:21:33 INFO - 'NO_EM_RESTART': '1', 17:21:33 INFO - 'PAGER': '/bin/cat', 17:21:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:33 INFO - 'PWD': '/builds/slave/test', 17:21:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:21:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:33 INFO - 'RUST_BACKTRACE': '1', 17:21:33 INFO - 'SHELL': '/bin/bash', 17:21:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oxdhcLXHA9/Listeners', 17:21:33 INFO - 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/', 17:21:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:33 INFO - 'USER': 'cltbld', 17:21:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:33 INFO - 'XPC_FLAGS': '0x0', 17:21:33 INFO - 'XPC_SERVICE_NAME': '0', 17:21:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0066', '--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 17:21:33 INFO - mozversion application_buildid: 20170428075320 17:21:33 INFO - mozversion application_changeset: b3b32894d9326d147238d9f071e1814ab3fdc850 17:21:33 INFO - mozversion application_display_name: Nightly 17:21:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:21:33 INFO - mozversion application_name: Firefox 17:21:33 INFO - mozversion application_remotingname: firefox 17:21:33 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:21:33 INFO - mozversion application_vendor: Mozilla 17:21:33 INFO - mozversion application_version: 55.0a1 17:21:33 INFO - mozversion platform_buildid: 20170428075320 17:21:33 INFO - mozversion platform_changeset: b3b32894d9326d147238d9f071e1814ab3fdc850 17:21:33 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:21:33 INFO - mozversion platform_version: 55.0a1 17:21:33 INFO - using testdate: 1493425293 17:21:33 INFO - actual date: 1493425293 17:21:33 INFO - starting webserver on 'localhost:49229' 17:21:33 INFO - SUITE-START | Running 2 tests 17:21:33 INFO - TEST-START | dromaeo_css 17:21:33 INFO - operating with platform_type : mac_ 17:21:33 INFO - Initialising browser for dromaeo_css test... 17:21:33 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/tmpimI_vR/profile http://localhost:49229/getInfo.html) 17:21:35 INFO - PID 1632 | 2017-04-28 17:21:35.793 plugin-container[1634:7629] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9937, name = 'com.apple.tsm.portname' 17:21:35 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:21:35 INFO - PID 1632 | 2017-04-28 17:21:35.796 plugin-container[1634:7629] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d07, name = 'com.apple.CFPasteboardClient' 17:21:35 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:21:35 INFO - PID 1632 | 2017-04-28 17:21:35.796 plugin-container[1634:7629] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:21:36 INFO - PID 1632 | __metrics Screen width/height:1600/1200 17:21:36 INFO - PID 1632 | colorDepth:24 17:21:36 INFO - PID 1632 | Browser inner width/height: 1024/689 17:21:36 INFO - PID 1632 | __metrics 17:21:36 ERROR - PID 1632 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3424: TypeError: this.tabs is undefined 17:21:36 INFO - TEST-INFO | 1632: exit 0 17:21:36 INFO - Browser initialized. 17:21:36 INFO - Running cycle 1/1 for dromaeo_css test... 17:21:36 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/tmpimI_vR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:21:39 INFO - PID 1635 | 2017-04-28 17:21:39.105 plugin-container[1636:7752] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 17:21:39 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:21:39 INFO - PID 1635 | 2017-04-28 17:21:39.106 plugin-container[1636:7752] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9817, name = 'com.apple.CFPasteboardClient' 17:21:39 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:21:39 INFO - PID 1635 | 2017-04-28 17:21:39.106 plugin-container[1636:7752] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:21:49 INFO - PID 1635 | RSS: Main: 251600896 17:21:49 INFO - PID 1635 | 17:24:53 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49229/tests/dromaeo/cssquery-ext.html) 17:24:54 INFO - PID 1635 | RSS: Main: 259805184 17:24:54 INFO - PID 1635 | 17:26:37 INFO - PID 1635 | ************************* 17:26:37 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 17:26:37 INFO - PID 1635 | 17:26:37 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:26:37 INFO - PID 1635 | Full stack: 17:26:37 INFO - PID 1635 | ************************* 17:26:37 INFO - PID 1635 | ************************* 17:26:37 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 17:26:37 INFO - PID 1635 | 17:26:37 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:26:37 INFO - PID 1635 | Full stack: 17:26:37 INFO - PID 1635 | ************************* 17:26:37 INFO - PID 1635 | ************************* 17:26:37 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 17:26:37 INFO - PID 1635 | 17:26:37 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:26:37 INFO - PID 1635 | Full stack: 17:26:37 INFO - PID 1635 | ************************* 17:26:37 INFO - PID 1635 | ************************* 17:26:37 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 17:26:37 INFO - PID 1635 | 17:26:37 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:26:37 INFO - PID 1635 | Full stack: 17:26:37 INFO - PID 1635 | ************************* 17:27:56 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-ext.html (next: http://localhost:49229/tests/dromaeo/cssquery-jquery.html) 17:27:57 INFO - PID 1635 | RSS: Main: 264249344 17:27:57 INFO - PID 1635 | 17:30:58 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49229/tests/dromaeo/cssquery-mootools.html) 17:30:59 INFO - PID 1635 | RSS: Main: 273440768 17:30:59 INFO - PID 1635 | 17:34:01 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49229/tests/dromaeo/cssquery-prototype.html) 17:34:01 INFO - PID 1635 | RSS: Main: 266264576 17:34:01 INFO - PID 1635 | 17:37:04 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49229/tests/dromaeo/cssquery-yui.html) 17:37:05 INFO - PID 1635 | RSS: Main: 275705856 17:37:05 INFO - PID 1635 | 17:40:06 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-yui.html (next: http://localhost:49229/tests/dromaeo/cssquery-dojo.html) 17:40:07 INFO - PID 1635 | RSS: Main: 270491648 17:40:07 INFO - PID 1635 | 17:40:07 INFO - PID 1635 | __start_tp_report 17:40:07 INFO - PID 1635 | _x_x_mozilla_page_load 17:40:07 INFO - PID 1635 | _x_x_mozilla_page_load_details 17:40:07 INFO - PID 1635 | |i|pagename|runs| 17:40:07 INFO - PID 1635 | |0;dojo.html;2835.4045650413414;3362.7309815214776;3423.6062852771925;3648.6168952260027;3659.9085022874433;6097.373446762071;6208.596441231322;6226.750929962802;6249.656268905214;6332.525060620449;6104.2469508561435;6163.41447562482;6170.568060235785;6207.906881396782;6242.687865606713;5426.104692725696;5436.537894278991;5473.480019398151;5493.23094766733;5503.6147469677135;4964.453910069908;5548.472895074961;5567.8886422271535;5570.359408667994;5585.134304182858;5354.250404943311;5405.972970135163;5482.451754824486;5516.91724624131;5555.388907220203;5463.617546771728;5483.067878460632;5504.366997795225;5545.057340252127;5556.527695145917;5396.622236443451;5470.398256191816;5476.644018138848;5483.945160548423;5485.890282194374;4865.8783530411665;6065.120557519135;6277.654728989904;6285.654289014103;6325.209348831396;5562.972185139089;6444.452221561173;6505.219373675142;6625.238097618787;6747.628880411576;6790.5925644461495;6816.045753594501;6817.147856517935;6828.283030281798;6843.452523798084;5261.921071183935;6267.4986001119805;6344.936550634481;6361.141245931833;6361.586496877688;6670.166229221348;6785.796426107225;6867.038614593961;6940.340667636567;6957.721691132298;6368.235811702532;6454.4513716333695;6503.544751867418;6531;6602.801915942529;5839.649621022752;6217.129601855744;6760.797176084725;6815.045893574904;6828.078209441761;5539.723013849291;5807.216025836458;6615;6629.204495460575;6660.333966603301;6467;6491.642959637265;6624.27133015368;6680.265170831205;6778.118844550276;5162.870928226825;5372.462753724674;5401.5948803839865;5402.37872644643;5457.781688732485;4737.8341758039;4762.119007983465;4769.403824521934;4779.593734532604;4786.6888652238185;5344.545713614309;6080.118382834465;6281.7173227204885;6311.495080393557;6479.092926990225;6724.831879202961;6785.084013658192;6796.796096117125;6810.250872404131;6819.386255237053;6444.935550644527;6448.613083214928;6450.645214513247;6467.579607325509;6470.45001174896;5930.703464826742;5972.134040564094;5984.640921544721;6006.759729610767;6013.639181649115;6143.232095988002;6321.241451025814;6810;6949.131358580177;7023.367896889305;6808.9659551701925;6818.28408017151;6855.760048398482;6857.657117144223;6864.107666003388;6929.1684997800585;6965.582065076112;7006.649667516705;7022.2275549688475;7044.436445084483;6487.870242595216;6592.505562082767;6625.271220165874;6708.161479815023;6723.9327606725265;5896.909071821727;6407.807765767035;6863.931360686329;6872.10662613857;6885.862282754417;6380.298167201511;6394.5523813334485;6468.870622587616;6590.373914478117;6606.339366063356;6629.635370054693;6667.533272670867;6669.066330713801;6669.2997235289595;6675.933240667531;6277.435030847246;6301.6219026858535;6329.810105696836;6337.588056776295;6534.248561415308;6522.380373864476;6581.967090164519;6602.60384376939;6603;6648.301928297459;6432.453241474527;6445.25879523842;6476.255230648348;6482.837929051811;6485.3514648534765 17:40:07 INFO - PID 1635 | |1;ext.html;761.406103239473;832.737687628397;836.7113345895664;1201.3932963853247;1248.1387842388745;36604.45093323599;37765.86702398925;37972;38048.048798780044;38098.42852357217;37971;38326.4251036234;38348.61651383485;38471.61528384715;38488;6812.795616131524;7917.327027202695;8078.192180781904;8471.279941205004;8530.36022298327;8413.831723365529;8417.663293468255;8512.1487851215;8513.935758030246;8521.190486903764;8482.872756908651;8551;8554.18735220153;8566.271866891322;8576.356773242002;8477.703280385187;8484.872726909101;8512.78718032048;8517.318614510887;8522.786930326729;7033.226345102034;7330.743423980135;8370.707025254087;8376.162383761635;8403.5378054207;32709.345813083844;33324.166895827555;36539;36565.988870612106;36658;3550.5029295898594;3603.261331427051;3605.4591811228524;3609.4224924012296;3614.150674591469;30234.395312093853;30512.847435762902;30618.846905765553;30651.69348306533;30744.69255307441;2615.777658898996;3098.9690103098906;3157.3053928135773;3176.491761318201;3178.825164615945;284240;284575.9999999979;310017;316316;318595;46605;48005.519944800806;48040;48231;48533;50101.74949125304;50131;52384;55486;55662;14491.565253042425;14567.562973110824;14577.708445831024;14578.854211457961;14597.12417254968;11571.84281571853;11631;11745.4714537846;11752.23610465317;11764.705882352839;14184;14187.432502699776;14198.432062717375;14200.147991120566;14294.928525357514;6073.271207455134;6150.415710507495;6195.411435913581;6202.038684003986;6269.185005949197;1010.4998025977188;1029.505837198149;1033.4832583708146;1065.0787069185253;1079.881213066562;23790.881045594662;24713.752862471145;24828.87585562097;24858.01703795556;24922.878470468873;930.3673502018557;1138.2715062360096;1166.9183157178916;1171.625079974417;1173.7652469506133;12522.87477125217;12638.747225055447;12747.61757147287;12759.10686251972;12819.551315703906;334811;336938;337384.31307843304;337808;338083;53368.39894803162;53577.732111339974;53665;53728.73135634297;53795;1456.0899437851342;1486.754685476884;1537.7155226283173;1552.1230504764771;1589.6235128482992;24350.87824560866;24479.26562203137;24483.877580612694;24547;24580.385490362885;37842.621573785014;48793.756031219615;50020.74989625029;50054.997800087585;50064.249036263755;303186;305581.472092647;306079;306832;308247;35542;35557.64442355543;35782.105447364025;35793.92618221458;35865.64134358623;42833.5716642842;43334.78332608317;44509.77451127366;44657;45013;29681;30325.241868953453;31002.68997310088;31024.379512409174;31154.753809847352;26068.73931260663;30427.54358684577;30779.9227027053;30819.845900770353;30964.380712386075;37129;37192;37197.44203836999;37250;37325.81337093297;24908.12821551237;29549.26126846846;30090.699093009665;30556.08331750051;31265.68734312628;19629.70555441698;21198.682019769407;23750.287491375286;23910.28269151928;24532.546745325326 17:40:07 INFO - PID 1635 | |2;jquery.html;440.15270604087124;445.18086720434405;445.9097506596373;465.1975342534128;466.1981392005749;19024.904875475637;21069.99999999998;21824.45438864027;21877.67183492249;22106.33680989569;18322.908385458053;22177.78022208778;22243;22435.55128897421;22741.545169096607;5631.380548139711;5904.5571582131515;6232.50139988802;6292.937070629304;6306.747730090791;5916;6107.389261073879;6164.59930104544;6171.7531298748;6179.5056395488255;5901.380355062721;6085.178500902388;6136.140940268366;6148.200733904586;6164.10620460033;6107.877842443148;6128.785492507785;6133.355997620252;6184.257889053342;6185.783497577609;5386.245925570423;5441.29263195786;5500;5506.944930550683;5537.141743029796;26815;26843.597346039824;26941.595876061874;27006.864965675242;27229;2251.8761468119246;2305.204704376987;2307.2501437033034;2309.872956987365;2318.547883162784;17185.31258749661;17185.570360740956;17324.480265592054;17534.03562804058;17768.200430980636;2109.124713244002;2131.8614290071096;2172.7718589548185;2176.706144670481;2180.7492138404127;149559;151442.24278878645;151723;153442;155027.4497255013;10962.780744385067;12018.33899135556;12479.93760031194;12509.374531273399;12587.349668596005;15558.299876505584;16206.513804585882;16483.505494835175;16499.83500164983;16516.83483165153;6533.705983230766;7196.280371962866;8249.63880959647;8620.956895215484;8878.73363799087;8144.104148543655;8174.100848906615;8205.302549283258;8370.3303735701;8373.790655233668;6384.776532821329;7725.266099720518;7877.7242796503;7898.526088434713;7941.523508589503;1935.5644979879414;2084.2080009596257;2173.8260939124834;2189.321310393783;2202.5484775620957;900.9549522523847;1003.5398495189481;1008.9243306752023;1022.4478781458079;1046.99476502617;19788.70316945247;20306.69539956902;20955.685664714736;21096.471150209967;21257.04343304555;1025.2618114957368;1025.917926565873;1053.6996955867542;1056.165629152976;1059.9841002384971;10433.061024507831;10875.619353322596;10934.781304373868;10964.780704385868;10988.725281868017;519277;532180;547582;559957;562715;107641;132733;135425;140702;140906.5909340914;1217.6955761059735;1237.888590026902;1263.943122559487;1264.014069026161;1275.8660340664283;16352.754708679142;21042.158313667893;22049.448763781034;22111.88944055334;22275.888620556794;48503.02993940031;48828.826126303094;53855.65360865853;58150.836983260946;59139.40860591339;77339;82374.58812705897;82792;83416.58291708502;83811.58094209734;8647.919010123735;8960.462372257685;8963.327750418614;8996.05541418168;9144.817103658022;13389.732205355644;14126.646833828825;14282.285885705882;14582.927085364505;14619.97660163821;10332.328398654063;11066.837982011773;11939.985101266142;12108.878911211128;12180.634580962585;11527.443795087556;11588.594399195988;11630.6510804676;11643;11707.239029463057;8803.547414676763;11270.323780573191;12207.877921220674;12417.503299868265;12439.626811195678;7416.44376671741;8700.956495217484;8940.418872773249;8956.373053886166;9096.90903090987;12054.638360849189;12073.335966521574;12156;12164.087693423206;12319.199252048587 17:40:07 INFO - PID 1635 | |3;mootools.html;1415.6602415420298;1463.8389777124507;1484.2430360516134;1499.8275198352185;1514.621344663834;11358.091352691785;11787.528498860036;11794.7641047179;11808.527658893634;12184.817227741569;9987.850182247254;11814.40927953601;11847.70380740482;12011.219270747395;12019.939900300486;2510.0712736287555;2579.7807186389177;2581.1353196679133;2584.043903755613;2588.68935727712;2269.171752310403;2493.463905260369;2519.282004628681;2538.263903467992;2543.275166577526;2488.8008959283216;2493.2021753039035;2499.5750722377147;2505.448801263728;2508.234988328559;2478.50429914017;2508.761667641571;2513.208339373092;2517.496500699858;2533.290678609992;2006.4582562707988;2433.0024689877064;2434.050720219116;2436.196055301764;2492.22740950306;11167.720806979809;11183.273087249385;11241.66275011751;11251.0436612888;11264.549418023269;6581.901271480931;6721.4958878084335;6803.761868334634;6817.5568588041615;6835.179778426621;4057.756534607933;4375.431193944767;4406.779661016936;4412.889677758049;4464.263396539567;1257.5158563952853;1271.6630093025356;1312.986870131296;1319.7624427603027;1325.4830616059744;16013.51959441207;16446;16483.505494835175;16500.33998640053;16746.581335466588;4560.40057167418;5453.263809385682;5597.74810133545;5641.743302678961;5663.433656634304;11802.88197118035;11841.526338946345;11894.643160705193;11905.166638335406;11935.761284774257;6551.541392102602;6593.6703164841565;6643.60138391698;6644.60132392058;6647.268800431949;5469.917951230735;5713.971485132633;6224.595401298901;6274.090256912722;6283.465905398092;4266.168097221074;4357.825686972549;4363.4109395231235;4403.581659742319;4416.24923762961;1139.922772979543;1243.4715246020405;1270.4410059573943;1296.9286689232183;1306.8497122830806;796.9800754981172;803.586153131138;826.2935190412111;832.2842355574202;858.1418581418582;6161.907571386433;6181.134641150153;6290.55966082379;6422.8715425691225;6457.741690332334;595.3926994465708;611.5841227964937;619.4332186049714;623.1525125828869;628.4030171337248;3782.6028267031566;3905.7851818150275;4314.69797114199;4370.409994650745;4531.320301954733;14860.108393496424;15412;15426.305816238297;15461.994970327118;15477.916545841908;15070.849291506944;15229.543113706606;15302.540923772081;15331.846681533041;15336.539903802905;8208.384371172186;8308.501489910625;8637.524936128573;8662.82674346522;8794.516301603473;5625.718714064198;6999.195092564217;7175.282471752783;7178.856422871618;7289.2710728928805;10464.999999999696;13259.80110298327;13360.532381366995;13678;13850.238236896665;13847.376868041163;14037.789433158716;14101;14139;14407.567772966828;7728.690852365842;9062.546872656209;9517.666881659386;9525.333226674344;9566;10845;10933;10956.671299861016;10957.178211634;10980.231383803057;8995.325350598601;9166.495842728715;9269.165775080275;9380.906190938276;9440.811183776423;8929.151730585583;9037.548122593975;9054.366194366594;9149.947756008145;9167.633294668138;8181.5500147490075;8327.750167494985;8387.287080598218;8396.832063358577;8417.326613870753;5965.701714914324;6005.339412664691;6082.878342433215;6141.477974372228;6198.566100372931;6083.330833608388;6140.9078863816185;6199.876002480015;6208.689565521616;6404.551681382445 17:40:07 INFO - PID 1635 | |4;prototype.html;389.98635047773314;396.40935006839817;408.6077365728901;410.2758631016256;418.9308764053931;5120.974395128019;5422.647527910688;5797.623154494965;6373.426391624753;6380.947143721286;4802.495737947517;4990.877052663149;5965.4929331006915;6116.562607787167;6168.9074663879965;3877.8836634900927;4688.835890743825;4761.142994261032;4784.712917224978;4798.352222449977;3876.612338766115;4771.642126840484;4782.019685964369;4788.976055119737;4797;3913.960860391388;4105.80931529856;4359.931816704911;4565.885852853688;4794.760261986904;4415.933760993587;4661.3707149534885;4740.170470167707;4756.66703330767;4776.49846766088;3677.3084381184685;3765.868194613203;4194.790260486994;4221.97889010553;4232.661387089056;5884.117382392633;6019.879602407971;6114.449699527064;6122.418370254819;6131.478824299896;4417.823287068545;5456.399796022434;5663.637271546972;5679.6773887275995;5682.204491371212;6694.129763130762;6695.129633147709;6714.865702685967;6759.357861003196;6830.565581227898;4641.025384669224;4776.737279449629;4825.686330388514;4840.443349014873;4842.878928026792;5779.971100144515;5807.0998995155815;6764.661766911635;6810.795676129724;7159.57042577447;4045.9190816183514;4117.279476091672;4639.280911458729;4647.209974304376;4648.883777905579;4576.794044267949;4697.694649847749;4739.478657347689;4761.6904901181315;4770.666053376231;5328.200769884548;5485.451454854562;5511.311086114236;5539.695316757537;5704.686242256716;6733.293004234582;6743.190817101881;6746.460283177334;6757.594544327258;6761.222459417131;1447.920364379948;1513.9632082121432;1692.771475850769;1708.9401870934707;1736.4356584110214;1184.703824043989;1186.163754553038;1203.506562309449;1208.8609809871978;1214.180428210954;5266.710330931759;6413.615183089029;6437.002264648987;6466.644334561644;6543.803685889431;5208.322918020633;6123.326434092246;6417.7753778617525;6896.655167241718;6985.89521157183;5359.678419294777;5384.273123128406;6247.812565623039;6405.263394709575;6444.323346048691;2747.079728291005;3444.0897441662255;3596.1908570571413;3601.783892966378;3621.0223239725656;6452.838489072012;6660.666966651745;6932.410745086724;7170.641467926583;7249.637518124073;7041.612711300928;7042.190148132928;7111.146662400545;7140.321669441395;7145.1068616422945;6370.713317900613;6808.714675297384;7048.718051277891;7146.606936618536;7250.4199664026755;6687.364700353459;6950.826229344307;6957.1651401832105;6980.301969802979;7042.190148133031;5544.085225937675;6412.583182093149;6496.285408604956;6817.5568588041615;6851.177858656993;6329.082283069021;6930.7227710891;6982.266861979419;7116.964415177891;7162.7493037743425;4106.7125301228625;4914.975425122851;4935.111679897653;4953.678010929422;5069.873253168553;5458.61789674719;5507.925954438846;6819.454443644596;6841.802684530287;6906.896396553955;5544.61187716856;6641;6710.463162946855;6719.966400167968;6753.189617245962;5213.973930130325;6369.560437363772;6579.572327798678;6616.55187580579;6659.567128136656;5809.390014048464;6162.969185154225;6330.493560515056;6480.935190648033;6493.675316234264;5456.345238571397;5560.249366335493;6338.207724034496;6438.581492202992;6605.654992477601;6445.323241059808;6464.450521705708;6474.676266186766;6482.578632389068;6494.772682956074 17:40:07 INFO - PID 1635 | |5;yui.html;851.3460141305882;856.2978357746647;885.7475619448459;892.3173772064372;902.8826252587165;13695.657608559779;14472.421103155873;17407;18838.811611883877;19471.41585752426;18121;18159;18460;18617.255309787626;18825.24699012038;973.3721749471587;982.9164521015705;996.5366104761284;998.3610489286826;1000.0549480740702;1012.8227560176977;1015.7511409704633;1017.3082304033254;1018.2668478695327;1024.7591815923251;1069.8983596558314;1071.6517131932114;1072.9967480405846;1074.9193810464208;1076.5962763963514;1031.0874875734953;1062.1715062251455;1063.8297872340427;1067.4769363012108;1069.5882085397138;771.4291424346;777.7861088200733;778.2373274191268;779.9220077992212;779.9376049915993;13871;16474.17629118539;16746.916265418597;16847.831521684748;16855.241514131892;16767.748483772753;17271.13644317779;17852.66105042127;17896;17897.910510447495;14011.789823152661;14036.859631403657;14150.929245353707;14188.92905535476;14205;3540.9822950885336;3582.42681171014;3583.050491619723;3593.2094939113354;3593.2633810068874;126645;135728;136041;138137;140011.49971250698;13584.117032392864;13644.522441714691;13678.726425471436;13803.585892423243;13902.51341203053;21814;25719.61420578693;25770.742292576833;25882.870585647328;26321.07876224323;7757.069151701832;7892.960535197287;7903.486273392211;7995.840083198304;7996.480228785111;3933.331333673282;3995.280849447069;4012.337964235868;4013.458183145238;4026.5772093929713;4319.892002699958;4381.036172042146;4393.143337049309;4425.491068527161;4447.933281000787;829.6017911402561;906.6056265524518;915.2220612479472;917.2616044084442;917.9770505737276;564.4919572384887;658.3416583416583;670.9932900671029;677.502036003537;699.4614147106699;11099.611513597147;11886.524539018515;12000.939995300143;12134.575289864812;12265.754684906431;535.6973310079793;664.1233571685425;665.7270519087151;666.5667316244383;692.4287462843174;4661.743604101807;5423.240746295522;5467.644603100785;5509.531689806331;5611.6352437091455;108730;111374.44312778546;111642.44178779054;112559;113565;99748;100579.49710251548;100923;101877;102280;86678;87615.12384876069;88000.55999720088;88381.11618883857;89535.55232223797;10851.457427128455;11067.391293478613;11181.664550063186;11357.829632555353;11411.37237451915;29358.853205733834;42891;43312.7946715898;43444.13111737684;44764;55847;55915;57013;57374.42625573691;57391.71304143452;13110;13931.303434828016;14262;17121.400750973655;17162.656746865243;18234.45296641103;18312.26750929948;18827.05864706732;18839.717404238672;18952;16650.084245366623;16840.242189101275;17616.735748963518;17622;17651.117444127998;16692.499225023268;16748.748768768233;16962.74555881638;17058.82941170622;17162.970221786734;16706.916465417595;16741.581460463098;16952.152392380085;17109.973401595944;17273.56816079608;11955.581554645545;12082.33547154915;12187.451564679433;12289.139760217056;12299.569515067287;10497.895021049691;11519.654410367702;13302.401391937192;13502.189868607915;14016.719665606835 17:40:07 INFO - PID 1635 | __end_tp_report 17:40:07 INFO - PID 1635 | __start_cc_report 17:40:07 INFO - PID 1635 | _x_x_mozilla_cycle_collect,426 17:40:07 INFO - PID 1635 | __end_cc_report 17:40:07 INFO - PID 1635 | __startTimestamp1493426407221__endTimestamp 17:40:07 INFO - PID 1635 | 17:40:07 INFO - PID 1635 | ------- Summary: start ------- 17:40:07 INFO - PID 1635 | Number of tests: 6 17:40:07 INFO - PID 1635 | 17:40:07 INFO - PID 1635 | [#0] dojo.html Cycles:165 Average:6145.88 Median:6380.30 stddev:757.04 (11.9%) stddev-sans-first:713.43 17:40:07 INFO - PID 1635 | Values: 2835.4 3362.7 3423.6 3648.6 3659.9 6097.4 6208.6 6226.8 6249.7 6332.5 6104.2 6163.4 6170.6 6207.9 6242.7 5426.1 5436.5 5473.5 5493.2 5503.6 4964.5 5548.5 5567.9 5570.4 5585.1 5354.3 5406.0 5482.5 5516.9 5555.4 5463.6 5483.1 5504.4 5545.1 5556.5 5396.6 5470.4 5476.6 5483.9 5485.9 4865.9 6065.1 6277.7 6285.7 6325.2 5563.0 6444.5 6505.2 6625.2 6747.6 6790.6 6816.0 6817.1 6828.3 6843.5 5261.9 6267.5 6344.9 6361.1 6361.6 6670.2 6785.8 6867.0 6940.3 6957.7 6368.2 6454.5 6503.5 6531.0 6602.8 5839.6 6217.1 6760.8 6815.0 6828.1 5539.7 5807.2 6615.0 6629.2 6660.3 6467.0 6491.6 6624.3 6680.3 6778.1 5162.9 5372.5 5401.6 5402.4 5457.8 4737.8 4762.1 4769.4 4779.6 4786.7 5344.5 6080.1 6281.7 6311.5 6479.1 6724.8 6785.1 6796.8 6810.3 6819.4 6444.9 6448.6 6450.6 6467.6 6470.5 5930.7 5972.1 5984.6 6006.8 6013.6 6143.2 6321.2 6810.0 6949.1 7023.4 6809.0 6818.3 6855.8 6857.7 6864.1 6929.2 6965.6 7006.6 7022.2 7044.4 6487.9 6592.5 6625.3 6708.2 6723.9 5896.9 6407.8 6863.9 6872.1 6885.9 6380.3 6394.6 6468.9 6590.4 6606.3 6629.6 6667.5 6669.1 6669.3 6675.9 6277.4 6301.6 6329.8 6337.6 6534.2 6522.4 6582.0 6602.6 6603.0 6648.3 6432.5 6445.3 6476.3 6482.8 6485.4 17:40:07 INFO - PID 1635 | 17:40:07 INFO - PID 1635 | [#1] ext.html Cycles:180 Average:46654.44 Median:337596.16 stddev:82921.25 (24.6%) stddev-sans-first:83082.28 17:40:07 INFO - PID 1635 | Values: 761.4 832.7 836.7 1201.4 1248.1 36604.5 37765.9 37972.0 38048.0 38098.4 37971.0 38326.4 38348.6 38471.6 38488.0 6812.8 7917.3 8078.2 8471.3 8530.4 8413.8 8417.7 8512.1 8513.9 8521.2 8482.9 8551.0 8554.2 8566.3 8576.4 8477.7 8484.9 8512.8 8517.3 8522.8 7033.2 7330.7 8370.7 8376.2 8403.5 32709.3 33324.2 36539.0 36566.0 36658.0 3550.5 3603.3 3605.5 3609.4 3614.2 30234.4 30512.8 30618.8 30651.7 30744.7 2615.8 3099.0 3157.3 3176.5 3178.8 284240.0 284576.0 310017.0 316316.0 318595.0 46605.0 48005.5 48040.0 48231.0 48533.0 50101.7 50131.0 52384.0 55486.0 55662.0 14491.6 14567.6 14577.7 14578.9 14597.1 11571.8 11631.0 11745.5 11752.2 11764.7 14184.0 14187.4 14198.4 14200.1 14294.9 6073.3 6150.4 6195.4 6202.0 6269.2 1010.5 1029.5 1033.5 1065.1 1079.9 23790.9 24713.8 24828.9 24858.0 24922.9 930.4 1138.3 1166.9 1171.6 1173.8 12522.9 12638.7 12747.6 12759.1 12819.6 334811.0 336938.0 337384.3 337808.0 338083.0 53368.4 53577.7 53665.0 53728.7 53795.0 1456.1 1486.8 1537.7 1552.1 1589.6 24350.9 24479.3 24483.9 24547.0 24580.4 37842.6 48793.8 50020.7 50055.0 50064.2 303186.0 305581.5 306079.0 306832.0 308247.0 35542.0 35557.6 35782.1 35793.9 35865.6 42833.6 43334.8 44509.8 44657.0 45013.0 29681.0 30325.2 31002.7 31024.4 31154.8 26068.7 30427.5 30779.9 30819.8 30964.4 37129.0 37192.0 37197.4 37250.0 37325.8 24908.1 29549.3 30090.7 30556.1 31265.7 19629.7 21198.7 23750.3 23910.3 24532.5 17:40:07 INFO - PID 1635 | 17:40:07 INFO - PID 1635 | [#2] jquery.html Cycles:180 Average:35386.11 Median:22077.89 stddev:92510.03 (419.0%) stddev-sans-first:92732.34 17:40:07 INFO - PID 1635 | Values: 440.2 445.2 445.9 465.2 466.2 19024.9 21070.0 21824.5 21877.7 22106.3 18322.9 22177.8 22243.0 22435.6 22741.5 5631.4 5904.6 6232.5 6292.9 6306.7 5916.0 6107.4 6164.6 6171.8 6179.5 5901.4 6085.2 6136.1 6148.2 6164.1 6107.9 6128.8 6133.4 6184.3 6185.8 5386.2 5441.3 5500.0 5506.9 5537.1 26815.0 26843.6 26941.6 27006.9 27229.0 2251.9 2305.2 2307.3 2309.9 2318.5 17185.3 17185.6 17324.5 17534.0 17768.2 2109.1 2131.9 2172.8 2176.7 2180.7 149559.0 151442.2 151723.0 153442.0 155027.4 10962.8 12018.3 12479.9 12509.4 12587.3 15558.3 16206.5 16483.5 16499.8 16516.8 6533.7 7196.3 8249.6 8621.0 8878.7 8144.1 8174.1 8205.3 8370.3 8373.8 6384.8 7725.3 7877.7 7898.5 7941.5 1935.6 2084.2 2173.8 2189.3 2202.5 901.0 1003.5 1008.9 1022.4 1047.0 19788.7 20306.7 20955.7 21096.5 21257.0 1025.3 1025.9 1053.7 1056.2 1060.0 10433.1 10875.6 10934.8 10964.8 10988.7 519277.0 532180.0 547582.0 559957.0 562715.0 107641.0 132733.0 135425.0 140702.0 140906.6 1217.7 1237.9 1263.9 1264.0 1275.9 16352.8 21042.2 22049.4 22111.9 22275.9 48503.0 48828.8 53855.7 58150.8 59139.4 77339.0 82374.6 82792.0 83416.6 83811.6 8647.9 8960.5 8963.3 8996.1 9144.8 13389.7 14126.6 14282.3 14582.9 14620.0 10332.3 11066.8 11940.0 12108.9 12180.6 11527.4 11588.6 11630.7 11643.0 11707.2 8803.5 11270.3 12207.9 12417.5 12439.6 7416.4 8701.0 8940.4 8956.4 9096.9 12054.6 12073.3 12156.0 12164.1 12319.2 17:40:07 INFO - PID 1635 | 17:40:07 INFO - PID 1635 | [#3] mootools.html Cycles:180 Average:7128.20 Median:3981.77 stddev:4542.17 (114.1%) stddev-sans-first:4534.63 17:40:07 INFO - PID 1635 | Values: 1415.7 1463.8 1484.2 1499.8 1514.6 11358.1 11787.5 11794.8 11808.5 12184.8 9987.9 11814.4 11847.7 12011.2 12019.9 2510.1 2579.8 2581.1 2584.0 2588.7 2269.2 2493.5 2519.3 2538.3 2543.3 2488.8 2493.2 2499.6 2505.4 2508.2 2478.5 2508.8 2513.2 2517.5 2533.3 2006.5 2433.0 2434.1 2436.2 2492.2 11167.7 11183.3 11241.7 11251.0 11264.5 6581.9 6721.5 6803.8 6817.6 6835.2 4057.8 4375.4 4406.8 4412.9 4464.3 1257.5 1271.7 1313.0 1319.8 1325.5 16013.5 16446.0 16483.5 16500.3 16746.6 4560.4 5453.3 5597.7 5641.7 5663.4 11802.9 11841.5 11894.6 11905.2 11935.8 6551.5 6593.7 6643.6 6644.6 6647.3 5469.9 5714.0 6224.6 6274.1 6283.5 4266.2 4357.8 4363.4 4403.6 4416.2 1139.9 1243.5 1270.4 1296.9 1306.8 797.0 803.6 826.3 832.3 858.1 6161.9 6181.1 6290.6 6422.9 6457.7 595.4 611.6 619.4 623.2 628.4 3782.6 3905.8 4314.7 4370.4 4531.3 14860.1 15412.0 15426.3 15462.0 15477.9 15070.8 15229.5 15302.5 15331.8 15336.5 8208.4 8308.5 8637.5 8662.8 8794.5 5625.7 6999.2 7175.3 7178.9 7289.3 10465.0 13259.8 13360.5 13678.0 13850.2 13847.4 14037.8 14101.0 14139.0 14407.6 7728.7 9062.5 9517.7 9525.3 9566.0 10845.0 10933.0 10956.7 10957.2 10980.2 8995.3 9166.5 9269.2 9380.9 9440.8 8929.2 9037.5 9054.4 9149.9 9167.6 8181.6 8327.8 8387.3 8396.8 8417.3 5965.7 6005.3 6082.9 6141.5 6198.6 6083.3 6140.9 6199.9 6208.7 6404.6 17:40:07 INFO - PID 1635 | 17:40:07 INFO - PID 1635 | [#4] prototype.html Cycles:180 Average:5373.61 Median:5788.80 stddev:1654.00 (28.6%) stddev-sans-first:1615.79 17:40:07 INFO - PID 1635 | Values: 390.0 396.4 408.6 410.3 418.9 5121.0 5422.6 5797.6 6373.4 6380.9 4802.5 4990.9 5965.5 6116.6 6168.9 3877.9 4688.8 4761.1 4784.7 4798.4 3876.6 4771.6 4782.0 4789.0 4797.0 3914.0 4105.8 4359.9 4565.9 4794.8 4415.9 4661.4 4740.2 4756.7 4776.5 3677.3 3765.9 4194.8 4222.0 4232.7 5884.1 6019.9 6114.4 6122.4 6131.5 4417.8 5456.4 5663.6 5679.7 5682.2 6694.1 6695.1 6714.9 6759.4 6830.6 4641.0 4776.7 4825.7 4840.4 4842.9 5780.0 5807.1 6764.7 6810.8 7159.6 4045.9 4117.3 4639.3 4647.2 4648.9 4576.8 4697.7 4739.5 4761.7 4770.7 5328.2 5485.5 5511.3 5539.7 5704.7 6733.3 6743.2 6746.5 6757.6 6761.2 1447.9 1514.0 1692.8 1708.9 1736.4 1184.7 1186.2 1203.5 1208.9 1214.2 5266.7 6413.6 6437.0 6466.6 6543.8 5208.3 6123.3 6417.8 6896.7 6985.9 5359.7 5384.3 6247.8 6405.3 6444.3 2747.1 3444.1 3596.2 3601.8 3621.0 6452.8 6660.7 6932.4 7170.6 7249.6 7041.6 7042.2 7111.1 7140.3 7145.1 6370.7 6808.7 7048.7 7146.6 7250.4 6687.4 6950.8 6957.2 6980.3 7042.2 5544.1 6412.6 6496.3 6817.6 6851.2 6329.1 6930.7 6982.3 7117.0 7162.7 4106.7 4915.0 4935.1 4953.7 5069.9 5458.6 5507.9 6819.5 6841.8 6906.9 5544.6 6641.0 6710.5 6720.0 6753.2 5214.0 6369.6 6579.6 6616.6 6659.6 5809.4 6163.0 6330.5 6480.9 6493.7 5456.3 5560.2 6338.2 6438.6 6605.7 6445.3 6464.5 6474.7 6482.6 6494.8 17:40:07 INFO - PID 1635 | 17:40:07 INFO - PID 1635 | [#5] yui.html Cycles:180 Average:22811.66 Median:17874.33 stddev:33140.37 (185.4%) stddev-sans-first:33192.32 17:40:07 INFO - PID 1635 | Values: 851.3 856.3 885.7 892.3 902.9 13695.7 14472.4 17407.0 18838.8 19471.4 18121.0 18159.0 18460.0 18617.3 18825.2 973.4 982.9 996.5 998.4 1000.1 1012.8 1015.8 1017.3 1018.3 1024.8 1069.9 1071.7 1073.0 1074.9 1076.6 1031.1 1062.2 1063.8 1067.5 1069.6 771.4 777.8 778.2 779.9 779.9 13871.0 16474.2 16746.9 16847.8 16855.2 16767.7 17271.1 17852.7 17896.0 17897.9 14011.8 14036.9 14150.9 14188.9 14205.0 3541.0 3582.4 3583.1 3593.2 3593.3 126645.0 135728.0 136041.0 138137.0 140011.5 13584.1 13644.5 13678.7 13803.6 13902.5 21814.0 25719.6 25770.7 25882.9 26321.1 7757.1 7893.0 7903.5 7995.8 7996.5 3933.3 3995.3 4012.3 4013.5 4026.6 4319.9 4381.0 4393.1 4425.5 4447.9 829.6 906.6 915.2 917.3 918.0 564.5 658.3 671.0 677.5 699.5 11099.6 11886.5 12000.9 12134.6 12265.8 535.7 664.1 665.7 666.6 692.4 4661.7 5423.2 5467.6 5509.5 5611.6 108730.0 111374.4 111642.4 112559.0 113565.0 99748.0 100579.5 100923.0 101877.0 102280.0 86678.0 87615.1 88000.6 88381.1 89535.6 10851.5 11067.4 11181.7 11357.8 11411.4 29358.9 42891.0 43312.8 43444.1 44764.0 55847.0 55915.0 57013.0 57374.4 57391.7 13110.0 13931.3 14262.0 17121.4 17162.7 18234.5 18312.3 18827.1 18839.7 18952.0 16650.1 16840.2 17616.7 17622.0 17651.1 16692.5 16748.7 16962.7 17058.8 17163.0 16706.9 16741.6 16952.2 17110.0 17273.6 11955.6 12082.3 12187.5 12289.1 12299.6 10497.9 11519.7 13302.4 13502.2 14016.7 17:40:07 INFO - PID 1635 | -------- Summary: end -------- 17:40:07 INFO - PID 1635 | 17:40:07 INFO - TEST-INFO | 1635: exit 0 17:40:07 INFO - TEST-OK | dromaeo_css | took 1114544ms 17:40:07 INFO - TEST-START | kraken 17:40:07 INFO - operating with platform_type : mac_ 17:40:07 INFO - Initialising browser for kraken test... 17:40:07 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/tmpxt0k0t/profile http://localhost:49229/getInfo.html) 17:40:10 INFO - PID 1647 | 2017-04-28 17:40:10.104 plugin-container[1648:10186] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 17:40:10 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:40:10 INFO - PID 1647 | 2017-04-28 17:40:10.108 plugin-container[1648:10186] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4527, name = 'com.apple.CFPasteboardClient' 17:40:10 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:40:10 INFO - PID 1647 | 2017-04-28 17:40:10.108 plugin-container[1648:10186] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:40:10 INFO - PID 1647 | __metrics Screen width/height:1600/1200 17:40:10 INFO - PID 1647 | colorDepth:24 17:40:10 INFO - PID 1647 | Browser inner width/height: 1024/689 17:40:10 INFO - PID 1647 | __metrics 17:40:10 ERROR - PID 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3424: TypeError: this.tabs is undefined 17:40:11 INFO - TEST-INFO | 1647: exit 0 17:40:11 INFO - Browser initialized. 17:40:11 INFO - Running cycle 1/1 for kraken test... 17:40:11 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/tmpxt0k0t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:40:13 INFO - PID 1649 | 2017-04-28 17:40:13.590 plugin-container[1650:10307] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x933f, name = 'com.apple.tsm.portname' 17:40:13 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:40:13 INFO - PID 1649 | 2017-04-28 17:40:13.591 plugin-container[1650:10307] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4717, name = 'com.apple.CFPasteboardClient' 17:40:13 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:40:13 INFO - PID 1649 | 2017-04-28 17:40:13.591 plugin-container[1650:10307] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:40:23 INFO - PID 1649 | RSS: Main: 250777600 17:40:23 INFO - PID 1649 | 17:40:26 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection) 17:40:27 INFO - PID 1649 | RSS: Main: 258887680 17:40:27 INFO - PID 1649 | 17:40:29 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-dft) 17:40:30 INFO - PID 1649 | RSS: Main: 265920512 17:40:30 INFO - PID 1649 | 17:40:33 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-fft) 17:40:34 INFO - PID 1649 | RSS: Main: 262021120 17:40:34 INFO - PID 1649 | 17:40:36 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator) 17:40:37 INFO - PID 1649 | RSS: Main: 263147520 17:40:37 INFO - PID 1649 | 17:40:38 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:40:39 INFO - PID 1649 | RSS: Main: 263725056 17:40:39 INFO - PID 1649 | 17:40:42 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom) 17:40:43 INFO - PID 1649 | RSS: Main: 271118336 17:40:43 INFO - PID 1649 | 17:40:46 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate) 17:40:47 INFO - PID 1649 | RSS: Main: 271511552 17:40:47 INFO - PID 1649 | 17:40:50 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial) 17:40:50 INFO - PID 1649 | RSS: Main: 271306752 17:40:50 INFO - PID 1649 | 17:40:52 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:40:52 INFO - PID 1649 | RSS: Main: 270995456 17:40:52 INFO - PID 1649 | 17:40:54 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:40:55 INFO - PID 1649 | RSS: Main: 271282176 17:40:55 INFO - PID 1649 | 17:40:57 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:40:58 INFO - PID 1649 | RSS: Main: 270712832 17:40:58 INFO - PID 1649 | 17:41:00 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:41:01 INFO - PID 1649 | RSS: Main: 270987264 17:41:01 INFO - PID 1649 | 17:41:04 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:41:05 INFO - PID 1649 | RSS: Main: 268148736 17:41:05 INFO - PID 1649 | 17:41:07 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49229/tests/kraken/driver.html?testName=ai-astar) 17:41:07 INFO - PID 1649 | RSS: Main: 270106624 17:41:07 INFO - PID 1649 | 17:41:07 INFO - PID 1649 | __start_tp_report 17:41:07 INFO - PID 1649 | _x_x_mozilla_page_load 17:41:07 INFO - PID 1649 | _x_x_mozilla_page_load_details 17:41:07 INFO - PID 1649 | |i|pagename|runs| 17:41:07 INFO - PID 1649 | |0;ai-astar;102;114;114;113;113;122;114;113;116;114 17:41:07 INFO - PID 1649 | |1;audio-beat-detection;147;112;110;151;115;112;110;121;154;137 17:41:07 INFO - PID 1649 | |2;audio-dft;199;199;196;204;210;230;194;196;201;198 17:41:07 INFO - PID 1649 | |3;audio-fft;80;50;63;110;87;89;48;68;83;86 17:41:07 INFO - PID 1649 | |4;audio-oscillator;87;70;82;69;67;68;83;70;69;90 17:41:07 INFO - PID 1649 | |5;imaging-gaussian-blur;138;88;137;83;139;137;103;117;87;88 17:41:07 INFO - PID 1649 | |6;imaging-darkroom;95;97;106;134;134;130;96;96;96;98 17:41:07 INFO - PID 1649 | |7;imaging-desaturate;98;94;90;91;90;91;89;94;92;92 17:41:07 INFO - PID 1649 | |8;json-parse-financial;50;52;50;60;47;47;51;50;49;49 17:41:07 INFO - PID 1649 | |9;json-stringify-tinderbox;60;57;57;60;77;83;53;58;58;56 17:41:07 INFO - PID 1649 | |10;stanford-crypto-aes;82;84;102;105;106;84;84;81;85;83 17:41:07 INFO - PID 1649 | |11;stanford-crypto-ccm;141;136;137;138;138;136;142;136;187;137 17:41:07 INFO - PID 1649 | |12;stanford-crypto-pbkdf2;221;192;190;194;194;191;193;227;254;190 17:41:07 INFO - PID 1649 | |13;stanford-crypto-sha256-iterative;71;69;69;77;97;81;69;71;97;107 17:41:07 INFO - PID 1649 | __end_tp_report 17:41:07 INFO - PID 1649 | __start_cc_report 17:41:07 INFO - PID 1649 | _x_x_mozilla_cycle_collect,1006 17:41:07 INFO - PID 1649 | __end_cc_report 17:41:07 INFO - PID 1649 | __startTimestamp1493426467611__endTimestamp 17:41:07 INFO - PID 1649 | 17:41:07 INFO - PID 1649 | ------- Summary: start ------- 17:41:07 INFO - PID 1649 | Number of tests: 14 17:41:07 INFO - PID 1649 | 17:41:07 INFO - PID 1649 | [#0] ai-astar Cycles:10 Average:113.50 Median:114.00 stddev:4.86 (4.3%) stddev-sans-first:2.86 17:41:07 INFO - PID 1649 | Values: 102.0 114.0 114.0 113.0 113.0 122.0 114.0 113.0 116.0 114.0 17:41:07 INFO - PID 1649 | 17:41:07 INFO - PID 1649 | [#1] audio-beat-detection Cycles:10 Average:126.90 Median:129.00 stddev:18.30 (14.2%) stddev-sans-first:17.90 17:41:07 INFO - PID 1649 | Values: 147.0 112.0 110.0 151.0 115.0 112.0 110.0 121.0 154.0 137.0 17:41:07 INFO - PID 1649 | 17:41:07 INFO - PID 1649 | [#2] audio-dft Cycles:10 Average:202.70 Median:200.00 stddev:10.64 (5.3%) stddev-sans-first:11.20 17:41:07 INFO - PID 1649 | Values: 199.0 199.0 196.0 204.0 210.0 230.0 194.0 196.0 201.0 198.0 17:41:07 INFO - PID 1649 | 17:41:07 INFO - PID 1649 | [#3] audio-fft Cycles:10 Average:76.40 Median:81.50 stddev:19.16 (23.5%) stddev-sans-first:20.27 17:41:07 INFO - PID 1649 | Values: 80.0 50.0 63.0 110.0 87.0 89.0 48.0 68.0 83.0 86.0 17:41:07 INFO - PID 1649 | 17:41:07 INFO - PID 1649 | [#4] audio-oscillator Cycles:10 Average:75.50 Median:76.00 stddev:8.91 (11.7%) stddev-sans-first:8.42 17:41:07 INFO - PID 1649 | Values: 87.0 70.0 82.0 69.0 67.0 68.0 83.0 70.0 69.0 90.0 17:41:07 INFO - PID 1649 | 17:41:07 INFO - PID 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:111.70 Median:111.00 stddev:24.45 (22.0%) stddev-sans-first:24.00 17:41:07 INFO - PID 1649 | Values: 138.0 88.0 137.0 83.0 139.0 137.0 103.0 117.0 87.0 88.0 17:41:07 INFO - PID 1649 | 17:41:07 INFO - PID 1649 | [#6] imaging-darkroom Cycles:10 Average:108.20 Median:96.00 stddev:17.20 (17.9%) stddev-sans-first:17.56 17:41:07 INFO - PID 1649 | Values: 95.0 97.0 106.0 134.0 134.0 130.0 96.0 96.0 96.0 98.0 17:41:07 INFO - PID 1649 | 17:41:07 INFO - PID 1649 | [#7] imaging-desaturate Cycles:10 Average:92.10 Median:92.00 stddev:2.64 (2.9%) stddev-sans-first:1.74 17:41:07 INFO - PID 1649 | Values: 98.0 94.0 90.0 91.0 90.0 91.0 89.0 94.0 92.0 92.0 17:41:07 INFO - PID 1649 | 17:41:07 INFO - PID 1649 | [#8] json-parse-financial Cycles:10 Average:50.50 Median:50.00 stddev:3.69 (7.4%) stddev-sans-first:3.91 17:41:07 INFO - PID 1649 | Values: 50.0 52.0 50.0 60.0 47.0 47.0 51.0 50.0 49.0 49.0 17:41:07 INFO - PID 1649 | 17:41:07 INFO - PID 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:61.90 Median:59.00 stddev:9.85 (16.7%) stddev-sans-first:10.42 17:41:07 INFO - PID 1649 | Values: 60.0 57.0 57.0 60.0 77.0 83.0 53.0 58.0 58.0 56.0 17:41:07 INFO - PID 1649 | 17:41:07 INFO - PID 1649 | [#10] stanford-crypto-aes Cycles:10 Average:89.60 Median:83.50 stddev:10.28 (12.3%) stddev-sans-first:10.53 17:41:07 INFO - PID 1649 | Values: 82.0 84.0 102.0 105.0 106.0 84.0 84.0 81.0 85.0 83.0 17:41:07 INFO - PID 1649 | 17:41:07 INFO - PID 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:142.80 Median:138.00 stddev:15.67 (11.4%) stddev-sans-first:16.61 17:41:07 INFO - PID 1649 | Values: 141.0 136.0 137.0 138.0 138.0 136.0 142.0 136.0 187.0 137.0 17:41:07 INFO - PID 1649 | 17:41:07 INFO - PID 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:204.60 Median:194.00 stddev:21.96 (11.3%) stddev-sans-first:22.48 17:41:07 INFO - PID 1649 | Values: 221.0 192.0 190.0 194.0 194.0 191.0 193.0 227.0 254.0 190.0 17:41:07 INFO - PID 1649 | 17:41:07 INFO - PID 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.80 Median:74.00 stddev:14.28 (19.3%) stddev-sans-first:14.70 17:41:07 INFO - PID 1649 | Values: 71.0 69.0 69.0 77.0 97.0 81.0 69.0 71.0 97.0 107.0 17:41:07 INFO - PID 1649 | -------- Summary: end -------- 17:41:07 INFO - PID 1649 | 17:41:08 INFO - TEST-INFO | 1649: exit 0 17:41:08 INFO - TEST-OK | kraken | took 60918ms 17:41:08 INFO - SUITE-END | took 1175s 17:41:09 INFO - Completed test suite (00:19:35) 17:41:09 INFO - javascript benchmark 17:41:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7936.87292148523, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2835.4045650413414, 3362.7309815214776, 3423.6062852771925, 3648.6168952260027, 3659.9085022874433, 6097.373446762071, 6208.596441231322, 6226.750929962802, 6249.656268905214, 6332.525060620449, 6104.2469508561435, 6163.41447562482, 6170.568060235785, 6207.906881396782, 6242.687865606713, 5426.104692725696, 5436.537894278991, 5473.480019398151, 5493.23094766733, 5503.6147469677135, 4964.453910069908, 5548.472895074961, 5567.8886422271535, 5570.359408667994, 5585.134304182858, 5354.250404943311, 5405.972970135163, 5482.451754824486, 5516.91724624131, 5555.388907220203, 5463.617546771728, 5483.067878460632, 5504.366997795225, 5545.057340252127, 5556.527695145917, 5396.622236443451, 5470.398256191816, 5476.644018138848, 5483.945160548423, 5485.890282194374, 4865.8783530411665, 6065.120557519135, 6277.654728989904, 6285.654289014103, 6325.209348831396, 5562.972185139089, 6444.452221561173, 6505.219373675142, 6625.238097618787, 6747.628880411576, 6790.5925644461495, 6816.045753594501, 6817.147856517935, 6828.283030281798, 6843.452523798084, 5261.921071183935, 6267.4986001119805, 6344.936550634481, 6361.141245931833, 6361.586496877688, 6670.166229221348, 6785.796426107225, 6867.038614593961, 6940.340667636567, 6957.721691132298, 6368.235811702532, 6454.4513716333695, 6503.544751867418, 6531.0, 6602.801915942529, 5839.649621022752, 6217.129601855744, 6760.797176084725, 6815.045893574904, 6828.078209441761, 5539.723013849291, 5807.216025836458, 6615.0, 6629.204495460575, 6660.333966603301, 6467.0, 6491.642959637265, 6624.27133015368, 6680.265170831205, 6778.118844550276, 5162.870928226825, 5372.462753724674, 5401.5948803839865, 5402.37872644643, 5457.781688732485, 4737.8341758039, 4762.119007983465, 4769.403824521934, 4779.593734532604, 4786.6888652238185, 5344.545713614309, 6080.118382834465, 6281.7173227204885, 6311.495080393557, 6479.092926990225, 6724.831879202961, 6785.084013658192, 6796.796096117125, 6810.250872404131, 6819.386255237053, 6444.935550644527, 6448.613083214928, 6450.645214513247, 6467.579607325509, 6470.45001174896, 5930.703464826742, 5972.134040564094, 5984.640921544721, 6006.759729610767, 6013.639181649115, 6143.232095988002, 6321.241451025814, 6810.0, 6949.131358580177, 7023.367896889305, 6808.9659551701925, 6818.28408017151, 6855.760048398482, 6857.657117144223, 6864.107666003388, 6929.1684997800585, 6965.582065076112, 7006.649667516705, 7022.2275549688475, 7044.436445084483, 6487.870242595216, 6592.505562082767, 6625.271220165874, 6708.161479815023, 6723.9327606725265, 5896.909071821727, 6407.807765767035, 6863.931360686329, 6872.10662613857, 6885.862282754417, 6380.298167201511, 6394.5523813334485, 6468.870622587616, 6590.373914478117, 6606.339366063356, 6629.635370054693, 6667.533272670867, 6669.066330713801, 6669.2997235289595, 6675.933240667531, 6277.435030847246, 6301.6219026858535, 6329.810105696836, 6337.588056776295, 6534.248561415308, 6522.380373864476, 6581.967090164519, 6602.60384376939, 6603.0, 6648.301928297459, 6432.453241474527, 6445.25879523842, 6476.255230648348, 6482.837929051811, 6485.3514648534765], "value": 6094.645336143573, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [761.406103239473, 832.737687628397, 836.7113345895664, 1201.3932963853247, 1248.1387842388745, 36604.45093323599, 37765.86702398925, 37972.0, 38048.048798780044, 38098.42852357217, 37971.0, 38326.4251036234, 38348.61651383485, 38471.61528384715, 38488.0, 6812.795616131524, 7917.327027202695, 8078.192180781904, 8471.279941205004, 8530.36022298327, 8413.831723365529, 8417.663293468255, 8512.1487851215, 8513.935758030246, 8521.190486903764, 8482.872756908651, 8551.0, 8554.18735220153, 8566.271866891322, 8576.356773242002, 8477.703280385187, 8484.872726909101, 8512.78718032048, 8517.318614510887, 8522.786930326729, 7033.226345102034, 7330.743423980135, 8370.707025254087, 8376.162383761635, 8403.5378054207, 32709.345813083844, 33324.166895827555, 36539.0, 36565.988870612106, 36658.0, 3550.5029295898594, 3603.261331427051, 3605.4591811228524, 3609.4224924012296, 3614.150674591469, 30234.395312093853, 30512.847435762902, 30618.846905765553, 30651.69348306533, 30744.69255307441, 2615.777658898996, 3098.9690103098906, 3157.3053928135773, 3176.491761318201, 3178.825164615945, 284240.0, 284575.9999999979, 310017.0, 316316.0, 318595.0, 46605.0, 48005.519944800806, 48040.0, 48231.0, 48533.0, 50101.74949125304, 50131.0, 52384.0, 55486.0, 55662.0, 14491.565253042425, 14567.562973110824, 14577.708445831024, 14578.854211457961, 14597.12417254968, 11571.84281571853, 11631.0, 11745.4714537846, 11752.23610465317, 11764.705882352839, 14184.0, 14187.432502699776, 14198.432062717375, 14200.147991120566, 14294.928525357514, 6073.271207455134, 6150.415710507495, 6195.411435913581, 6202.038684003986, 6269.185005949197, 1010.4998025977188, 1029.505837198149, 1033.4832583708146, 1065.0787069185253, 1079.881213066562, 23790.881045594662, 24713.752862471145, 24828.87585562097, 24858.01703795556, 24922.878470468873, 930.3673502018557, 1138.2715062360096, 1166.9183157178916, 1171.625079974417, 1173.7652469506133, 12522.87477125217, 12638.747225055447, 12747.61757147287, 12759.10686251972, 12819.551315703906, 334811.0, 336938.0, 337384.31307843304, 337808.0, 338083.0, 53368.39894803162, 53577.732111339974, 53665.0, 53728.73135634297, 53795.0, 1456.0899437851342, 1486.754685476884, 1537.7155226283173, 1552.1230504764771, 1589.6235128482992, 24350.87824560866, 24479.26562203137, 24483.877580612694, 24547.0, 24580.385490362885, 37842.621573785014, 48793.756031219615, 50020.74989625029, 50054.997800087585, 50064.249036263755, 303186.0, 305581.472092647, 306079.0, 306832.0, 308247.0, 35542.0, 35557.64442355543, 35782.105447364025, 35793.92618221458, 35865.64134358623, 42833.5716642842, 43334.78332608317, 44509.77451127366, 44657.0, 45013.0, 29681.0, 30325.241868953453, 31002.68997310088, 31024.379512409174, 31154.753809847352, 26068.73931260663, 30427.54358684577, 30779.9227027053, 30819.845900770353, 30964.380712386075, 37129.0, 37192.0, 37197.44203836999, 37250.0, 37325.81337093297, 24908.12821551237, 29549.26126846846, 30090.699093009665, 30556.08331750051, 31265.68734312628, 19629.70555441698, 21198.682019769407, 23750.287491375286, 23910.28269151928, 24532.546745325326], "value": 17747.04369999096, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [440.15270604087124, 445.18086720434405, 445.9097506596373, 465.1975342534128, 466.1981392005749, 19024.904875475637, 21069.99999999998, 21824.45438864027, 21877.67183492249, 22106.33680989569, 18322.908385458053, 22177.78022208778, 22243.0, 22435.55128897421, 22741.545169096607, 5631.380548139711, 5904.5571582131515, 6232.50139988802, 6292.937070629304, 6306.747730090791, 5916.0, 6107.389261073879, 6164.59930104544, 6171.7531298748, 6179.5056395488255, 5901.380355062721, 6085.178500902388, 6136.140940268366, 6148.200733904586, 6164.10620460033, 6107.877842443148, 6128.785492507785, 6133.355997620252, 6184.257889053342, 6185.783497577609, 5386.245925570423, 5441.29263195786, 5500.0, 5506.944930550683, 5537.141743029796, 26815.0, 26843.597346039824, 26941.595876061874, 27006.864965675242, 27229.0, 2251.8761468119246, 2305.204704376987, 2307.2501437033034, 2309.872956987365, 2318.547883162784, 17185.31258749661, 17185.570360740956, 17324.480265592054, 17534.03562804058, 17768.200430980636, 2109.124713244002, 2131.8614290071096, 2172.7718589548185, 2176.706144670481, 2180.7492138404127, 149559.0, 151442.24278878645, 151723.0, 153442.0, 155027.4497255013, 10962.780744385067, 12018.33899135556, 12479.93760031194, 12509.374531273399, 12587.349668596005, 15558.299876505584, 16206.513804585882, 16483.505494835175, 16499.83500164983, 16516.83483165153, 6533.705983230766, 7196.280371962866, 8249.63880959647, 8620.956895215484, 8878.73363799087, 8144.104148543655, 8174.100848906615, 8205.302549283258, 8370.3303735701, 8373.790655233668, 6384.776532821329, 7725.266099720518, 7877.7242796503, 7898.526088434713, 7941.523508589503, 1935.5644979879414, 2084.2080009596257, 2173.8260939124834, 2189.321310393783, 2202.5484775620957, 900.9549522523847, 1003.5398495189481, 1008.9243306752023, 1022.4478781458079, 1046.99476502617, 19788.70316945247, 20306.69539956902, 20955.685664714736, 21096.471150209967, 21257.04343304555, 1025.2618114957368, 1025.917926565873, 1053.6996955867542, 1056.165629152976, 1059.9841002384971, 10433.061024507831, 10875.619353322596, 10934.781304373868, 10964.780704385868, 10988.725281868017, 519277.0, 532180.0, 547582.0, 559957.0, 562715.0, 107641.0, 132733.0, 135425.0, 140702.0, 140906.5909340914, 1217.6955761059735, 1237.888590026902, 1263.943122559487, 1264.014069026161, 1275.8660340664283, 16352.754708679142, 21042.158313667893, 22049.448763781034, 22111.88944055334, 22275.888620556794, 48503.02993940031, 48828.826126303094, 53855.65360865853, 58150.836983260946, 59139.40860591339, 77339.0, 82374.58812705897, 82792.0, 83416.58291708502, 83811.58094209734, 8647.919010123735, 8960.462372257685, 8963.327750418614, 8996.05541418168, 9144.817103658022, 13389.732205355644, 14126.646833828825, 14282.285885705882, 14582.927085364505, 14619.97660163821, 10332.328398654063, 11066.837982011773, 11939.985101266142, 12108.878911211128, 12180.634580962585, 11527.443795087556, 11588.594399195988, 11630.6510804676, 11643.0, 11707.239029463057, 8803.547414676763, 11270.323780573191, 12207.877921220674, 12417.503299868265, 12439.626811195678, 7416.44376671741, 8700.956495217484, 8940.418872773249, 8956.373053886166, 9096.90903090987, 12054.638360849189, 12073.335966521574, 12156.0, 12164.087693423206, 12319.199252048587], "value": 10424.59033952652, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1415.6602415420298, 1463.8389777124507, 1484.2430360516134, 1499.8275198352185, 1514.621344663834, 11358.091352691785, 11787.528498860036, 11794.7641047179, 11808.527658893634, 12184.817227741569, 9987.850182247254, 11814.40927953601, 11847.70380740482, 12011.219270747395, 12019.939900300486, 2510.0712736287555, 2579.7807186389177, 2581.1353196679133, 2584.043903755613, 2588.68935727712, 2269.171752310403, 2493.463905260369, 2519.282004628681, 2538.263903467992, 2543.275166577526, 2488.8008959283216, 2493.2021753039035, 2499.5750722377147, 2505.448801263728, 2508.234988328559, 2478.50429914017, 2508.761667641571, 2513.208339373092, 2517.496500699858, 2533.290678609992, 2006.4582562707988, 2433.0024689877064, 2434.050720219116, 2436.196055301764, 2492.22740950306, 11167.720806979809, 11183.273087249385, 11241.66275011751, 11251.0436612888, 11264.549418023269, 6581.901271480931, 6721.4958878084335, 6803.761868334634, 6817.5568588041615, 6835.179778426621, 4057.756534607933, 4375.431193944767, 4406.779661016936, 4412.889677758049, 4464.263396539567, 1257.5158563952853, 1271.6630093025356, 1312.986870131296, 1319.7624427603027, 1325.4830616059744, 16013.51959441207, 16446.0, 16483.505494835175, 16500.33998640053, 16746.581335466588, 4560.40057167418, 5453.263809385682, 5597.74810133545, 5641.743302678961, 5663.433656634304, 11802.88197118035, 11841.526338946345, 11894.643160705193, 11905.166638335406, 11935.761284774257, 6551.541392102602, 6593.6703164841565, 6643.60138391698, 6644.60132392058, 6647.268800431949, 5469.917951230735, 5713.971485132633, 6224.595401298901, 6274.090256912722, 6283.465905398092, 4266.168097221074, 4357.825686972549, 4363.4109395231235, 4403.581659742319, 4416.24923762961, 1139.922772979543, 1243.4715246020405, 1270.4410059573943, 1296.9286689232183, 1306.8497122830806, 796.9800754981172, 803.586153131138, 826.2935190412111, 832.2842355574202, 858.1418581418582, 6161.907571386433, 6181.134641150153, 6290.55966082379, 6422.8715425691225, 6457.741690332334, 595.3926994465708, 611.5841227964937, 619.4332186049714, 623.1525125828869, 628.4030171337248, 3782.6028267031566, 3905.7851818150275, 4314.69797114199, 4370.409994650745, 4531.320301954733, 14860.108393496424, 15412.0, 15426.305816238297, 15461.994970327118, 15477.916545841908, 15070.849291506944, 15229.543113706606, 15302.540923772081, 15331.846681533041, 15336.539903802905, 8208.384371172186, 8308.501489910625, 8637.524936128573, 8662.82674346522, 8794.516301603473, 5625.718714064198, 6999.195092564217, 7175.282471752783, 7178.856422871618, 7289.2710728928805, 10464.999999999696, 13259.80110298327, 13360.532381366995, 13678.0, 13850.238236896665, 13847.376868041163, 14037.789433158716, 14101.0, 14139.0, 14407.567772966828, 7728.690852365842, 9062.546872656209, 9517.666881659386, 9525.333226674344, 9566.0, 10845.0, 10933.0, 10956.671299861016, 10957.178211634, 10980.231383803057, 8995.325350598601, 9166.495842728715, 9269.165775080275, 9380.906190938276, 9440.811183776423, 8929.151730585583, 9037.548122593975, 9054.366194366594, 9149.947756008145, 9167.633294668138, 8181.5500147490075, 8327.750167494985, 8387.287080598218, 8396.832063358577, 8417.326613870753, 5965.701714914324, 6005.339412664691, 6082.878342433215, 6141.477974372228, 6198.566100372931, 6083.330833608388, 6140.9078863816185, 6199.876002480015, 6208.689565521616, 6404.551681382445], "value": 5344.6724882534245, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.98635047773314, 396.40935006839817, 408.6077365728901, 410.2758631016256, 418.9308764053931, 5120.974395128019, 5422.647527910688, 5797.623154494965, 6373.426391624753, 6380.947143721286, 4802.495737947517, 4990.877052663149, 5965.4929331006915, 6116.562607787167, 6168.9074663879965, 3877.8836634900927, 4688.835890743825, 4761.142994261032, 4784.712917224978, 4798.352222449977, 3876.612338766115, 4771.642126840484, 4782.019685964369, 4788.976055119737, 4797.0, 3913.960860391388, 4105.80931529856, 4359.931816704911, 4565.885852853688, 4794.760261986904, 4415.933760993587, 4661.3707149534885, 4740.170470167707, 4756.66703330767, 4776.49846766088, 3677.3084381184685, 3765.868194613203, 4194.790260486994, 4221.97889010553, 4232.661387089056, 5884.117382392633, 6019.879602407971, 6114.449699527064, 6122.418370254819, 6131.478824299896, 4417.823287068545, 5456.399796022434, 5663.637271546972, 5679.6773887275995, 5682.204491371212, 6694.129763130762, 6695.129633147709, 6714.865702685967, 6759.357861003196, 6830.565581227898, 4641.025384669224, 4776.737279449629, 4825.686330388514, 4840.443349014873, 4842.878928026792, 5779.971100144515, 5807.0998995155815, 6764.661766911635, 6810.795676129724, 7159.57042577447, 4045.9190816183514, 4117.279476091672, 4639.280911458729, 4647.209974304376, 4648.883777905579, 4576.794044267949, 4697.694649847749, 4739.478657347689, 4761.6904901181315, 4770.666053376231, 5328.200769884548, 5485.451454854562, 5511.311086114236, 5539.695316757537, 5704.686242256716, 6733.293004234582, 6743.190817101881, 6746.460283177334, 6757.594544327258, 6761.222459417131, 1447.920364379948, 1513.9632082121432, 1692.771475850769, 1708.9401870934707, 1736.4356584110214, 1184.703824043989, 1186.163754553038, 1203.506562309449, 1208.8609809871978, 1214.180428210954, 5266.710330931759, 6413.615183089029, 6437.002264648987, 6466.644334561644, 6543.803685889431, 5208.322918020633, 6123.326434092246, 6417.7753778617525, 6896.655167241718, 6985.89521157183, 5359.678419294777, 5384.273123128406, 6247.812565623039, 6405.263394709575, 6444.323346048691, 2747.079728291005, 3444.0897441662255, 3596.1908570571413, 3601.783892966378, 3621.0223239725656, 6452.838489072012, 6660.666966651745, 6932.410745086724, 7170.641467926583, 7249.637518124073, 7041.612711300928, 7042.190148132928, 7111.146662400545, 7140.321669441395, 7145.1068616422945, 6370.713317900613, 6808.714675297384, 7048.718051277891, 7146.606936618536, 7250.4199664026755, 6687.364700353459, 6950.826229344307, 6957.1651401832105, 6980.301969802979, 7042.190148133031, 5544.085225937675, 6412.583182093149, 6496.285408604956, 6817.5568588041615, 6851.177858656993, 6329.082283069021, 6930.7227710891, 6982.266861979419, 7116.964415177891, 7162.7493037743425, 4106.7125301228625, 4914.975425122851, 4935.111679897653, 4953.678010929422, 5069.873253168553, 5458.61789674719, 5507.925954438846, 6819.454443644596, 6841.802684530287, 6906.896396553955, 5544.61187716856, 6641.0, 6710.463162946855, 6719.966400167968, 6753.189617245962, 5213.973930130325, 6369.560437363772, 6579.572327798678, 6616.55187580579, 6659.567128136656, 5809.390014048464, 6162.969185154225, 6330.493560515056, 6480.935190648033, 6493.675316234264, 5456.345238571397, 5560.249366335493, 6338.207724034496, 6438.581492202992, 6605.654992477601, 6445.323241059808, 6464.450521705708, 6474.676266186766, 6482.578632389068, 6494.772682956074], "value": 4875.583440371908, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [851.3460141305882, 856.2978357746647, 885.7475619448459, 892.3173772064372, 902.8826252587165, 13695.657608559779, 14472.421103155873, 17407.0, 18838.811611883877, 19471.41585752426, 18121.0, 18159.0, 18460.0, 18617.255309787626, 18825.24699012038, 973.3721749471587, 982.9164521015705, 996.5366104761284, 998.3610489286826, 1000.0549480740702, 1012.8227560176977, 1015.7511409704633, 1017.3082304033254, 1018.2668478695327, 1024.7591815923251, 1069.8983596558314, 1071.6517131932114, 1072.9967480405846, 1074.9193810464208, 1076.5962763963514, 1031.0874875734953, 1062.1715062251455, 1063.8297872340427, 1067.4769363012108, 1069.5882085397138, 771.4291424346, 777.7861088200733, 778.2373274191268, 779.9220077992212, 779.9376049915993, 13871.0, 16474.17629118539, 16746.916265418597, 16847.831521684748, 16855.241514131892, 16767.748483772753, 17271.13644317779, 17852.66105042127, 17896.0, 17897.910510447495, 14011.789823152661, 14036.859631403657, 14150.929245353707, 14188.92905535476, 14205.0, 3540.9822950885336, 3582.42681171014, 3583.050491619723, 3593.2094939113354, 3593.2633810068874, 126645.0, 135728.0, 136041.0, 138137.0, 140011.49971250698, 13584.117032392864, 13644.522441714691, 13678.726425471436, 13803.585892423243, 13902.51341203053, 21814.0, 25719.61420578693, 25770.742292576833, 25882.870585647328, 26321.07876224323, 7757.069151701832, 7892.960535197287, 7903.486273392211, 7995.840083198304, 7996.480228785111, 3933.331333673282, 3995.280849447069, 4012.337964235868, 4013.458183145238, 4026.5772093929713, 4319.892002699958, 4381.036172042146, 4393.143337049309, 4425.491068527161, 4447.933281000787, 829.6017911402561, 906.6056265524518, 915.2220612479472, 917.2616044084442, 917.9770505737276, 564.4919572384887, 658.3416583416583, 670.9932900671029, 677.502036003537, 699.4614147106699, 11099.611513597147, 11886.524539018515, 12000.939995300143, 12134.575289864812, 12265.754684906431, 535.6973310079793, 664.1233571685425, 665.7270519087151, 666.5667316244383, 692.4287462843174, 4661.743604101807, 5423.240746295522, 5467.644603100785, 5509.531689806331, 5611.6352437091455, 108730.0, 111374.44312778546, 111642.44178779054, 112559.0, 113565.0, 99748.0, 100579.49710251548, 100923.0, 101877.0, 102280.0, 86678.0, 87615.12384876069, 88000.55999720088, 88381.11618883857, 89535.55232223797, 10851.457427128455, 11067.391293478613, 11181.664550063186, 11357.829632555353, 11411.37237451915, 29358.853205733834, 42891.0, 43312.7946715898, 43444.13111737684, 44764.0, 55847.0, 55915.0, 57013.0, 57374.42625573691, 57391.71304143452, 13110.0, 13931.303434828016, 14262.0, 17121.400750973655, 17162.656746865243, 18234.45296641103, 18312.26750929948, 18827.05864706732, 18839.717404238672, 18952.0, 16650.084245366623, 16840.242189101275, 17616.735748963518, 17622.0, 17651.117444127998, 16692.499225023268, 16748.748768768233, 16962.74555881638, 17058.82941170622, 17162.970221786734, 16706.916465417595, 16741.581460463098, 16952.152392380085, 17109.973401595944, 17273.56816079608, 11955.581554645545, 12082.33547154915, 12187.451564679433, 12289.139760217056, 12299.569515067287, 10497.895021049691, 11519.654410367702, 13302.401391937192, 13502.189868607915, 14016.719665606835], "value": 8507.167063176617, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1537.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 114.0, 114.0, 113.0, 113.0, 122.0, 114.0, 113.0, 116.0, 114.0], "value": 113.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 112.0, 110.0, 151.0, 115.0, 112.0, 110.0, 121.0, 154.0, 137.0], "value": 126.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 199.0, 196.0, 204.0, 210.0, 230.0, 194.0, 196.0, 201.0, 198.0], "value": 202.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 50.0, 63.0, 110.0, 87.0, 89.0, 48.0, 68.0, 83.0, 86.0], "value": 76.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 82.0, 69.0, 67.0, 68.0, 83.0, 70.0, 69.0, 90.0], "value": 75.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 88.0, 137.0, 83.0, 139.0, 137.0, 103.0, 117.0, 87.0, 88.0], "value": 111.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 106.0, 134.0, 134.0, 130.0, 96.0, 96.0, 96.0, 98.0], "value": 108.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 94.0, 90.0, 91.0, 90.0, 91.0, 89.0, 94.0, 92.0, 92.0], "value": 92.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 52.0, 50.0, 60.0, 47.0, 47.0, 51.0, 50.0, 49.0, 49.0], "value": 50.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 57.0, 57.0, 60.0, 77.0, 83.0, 53.0, 58.0, 58.0, 56.0], "value": 61.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 102.0, 105.0, 106.0, 84.0, 84.0, 81.0, 85.0, 83.0], "value": 89.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 136.0, 137.0, 138.0, 138.0, 136.0, 142.0, 136.0, 187.0, 137.0], "value": 142.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [221.0, 192.0, 190.0, 194.0, 194.0, 191.0, 193.0, 227.0, 254.0, 190.0], "value": 204.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 69.0, 77.0, 97.0, 81.0, 69.0, 71.0, 97.0, 107.0], "value": 80.8, "unit": "score"}]}]} 17:41:09 INFO - Return code: 0 17:41:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:41:09 ERROR - # TBPL SUCCESS # 17:41:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:41:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:41:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:41:09 INFO - 2017-04-28 17:41:09.430 screenresolution[1654:10705] starting screenresolution argv=screenresolution get 17:41:09 INFO - 2017-04-28 17:41:09.432 screenresolution[1654:10705] Display 0: 1600x1200x32@60 17:41:09 INFO - 2017-04-28 17:41:09.438 screenresolution[1655:10708] starting screenresolution argv=screenresolution list 17:41:09 INFO - Available Modes on Display 0 17:41:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:41:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:41:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:41:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:41:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:41:09 INFO - Intel Iris: 17:41:09 INFO - Chipset Model: Intel Iris 17:41:09 INFO - Type: GPU 17:41:09 INFO - Bus: Built-In 17:41:09 INFO - VRAM (Dynamic, Max): 1536 MB 17:41:09 INFO - Vendor: Intel (0x8086) 17:41:09 INFO - Device ID: 0x0a2e 17:41:09 INFO - Revision ID: 0x0009 17:41:09 INFO - Displays: 17:41:09 INFO - Display: 17:41:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:41:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:41:09 INFO - Display Serial Number: 1600x1200 60 17:41:09 INFO - Main Display: Yes 17:41:09 INFO - Mirror: Off 17:41:09 INFO - Online: Yes 17:41:09 INFO - Rotation: Supported 17:41:09 INFO - Return code: 0 17:41:09 INFO - Running post-action listener: _resource_record_post_action 17:41:09 INFO - [mozharness: 2017-04-29 00:41:09.672024Z] Finished run-tests step (success) 17:41:09 INFO - Running post-run listener: _resource_record_post_run 17:41:09 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 37419520; Write bytes: 514291200; Read time: 380; Write time: 1765 17:41:09 INFO - TinderboxPrint: CPU usage
54.2% 17:41:09 INFO - TinderboxPrint: I/O read bytes / time
37,419,520 / 380 17:41:09 INFO - TinderboxPrint: I/O write bytes / time
514,291,200 / 1,765 17:41:09 INFO - TinderboxPrint: CPU idle
2,192.6 (45.8%) 17:41:09 INFO - TinderboxPrint: CPU system
961.1 (20.1%) 17:41:09 INFO - TinderboxPrint: CPU user
1,632.0 (34.1%) 17:41:09 INFO - TinderboxPrint: Swap in / out
245,997,568 / 0 17:41:09 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 194351616; Write bytes: 195704320; Read time: 15017; Write time: 475 17:41:09 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 36465152; Write bytes: 301744128; Read time: 365; Write time: 1240 17:41:10 INFO - Running post-run listener: _upload_blobber_files 17:41:10 INFO - Blob upload gear active. 17:41:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:41:10 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 17:41:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:41:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:41:10 INFO - (blobuploader) - INFO - Open directory for files ... 17:41:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:41:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:41:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:41:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:41:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:41:11 INFO - (blobuploader) - INFO - Done attempting. 17:41:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:41:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:41:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:41:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:41:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:41:12 INFO - (blobuploader) - INFO - Done attempting. 17:41:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:41:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:41:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:41:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:41:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:41:13 INFO - (blobuploader) - INFO - Done attempting. 17:41:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:41:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:41:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:41:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:41:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:41:15 INFO - (blobuploader) - INFO - Done attempting. 17:41:15 INFO - (blobuploader) - INFO - Iteration through files over. 17:41:15 INFO - Return code: 0 17:41:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:41:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:41:15 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1ebe5a61ba44844921742c02ed91c9311c7377c8295810e84f2625a015eee53554a5de0ff5257448d4043bfac5383e17328ad66f17fb6f9439b7482498faaff", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e5070d73229101a53f8be721b094d088868f9344e7c885282c0c0eccbfb66dacd277938e1c572684dd4e8e9be16c64633bda61050c29b428af4f77bbd026a895", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b2f2df6dcc6cf942b4cb96f44167c2578264a3fea3528f813b0f5ed9b8bfd304d3efc00f03e0762d19eec61672259cd7df253f50df28eead27b47754b6523855", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3d40dc42c349cb2eae3e342f26ef2f21cfaec4d97d20cba6bcf86f5531364d46116ad2949d023199f3f02f63234626728ad090fa5384b2752dc236147b46462"} 17:41:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:41:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:41:15 INFO - Contents: 17:41:15 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1ebe5a61ba44844921742c02ed91c9311c7377c8295810e84f2625a015eee53554a5de0ff5257448d4043bfac5383e17328ad66f17fb6f9439b7482498faaff", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e5070d73229101a53f8be721b094d088868f9344e7c885282c0c0eccbfb66dacd277938e1c572684dd4e8e9be16c64633bda61050c29b428af4f77bbd026a895", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b2f2df6dcc6cf942b4cb96f44167c2578264a3fea3528f813b0f5ed9b8bfd304d3efc00f03e0762d19eec61672259cd7df253f50df28eead27b47754b6523855", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3d40dc42c349cb2eae3e342f26ef2f21cfaec4d97d20cba6bcf86f5531364d46116ad2949d023199f3f02f63234626728ad090fa5384b2752dc236147b46462"} 17:41:15 INFO - Running post-run listener: copy_logs_to_upload_dir 17:41:15 INFO - Copying logs to upload dir... 17:41:15 INFO - mkdir: /builds/slave/test/build/upload/logs 17:41:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.952861 ========= master_lag: 0.48 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-04-28 17:41:16.208736) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-28 17:41:16.212595) ========= 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.8EN5Xi1oDJ/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.oxdhcLXHA9/Listeners TMPDIR=/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1ebe5a61ba44844921742c02ed91c9311c7377c8295810e84f2625a015eee53554a5de0ff5257448d4043bfac5383e17328ad66f17fb6f9439b7482498faaff", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e5070d73229101a53f8be721b094d088868f9344e7c885282c0c0eccbfb66dacd277938e1c572684dd4e8e9be16c64633bda61050c29b428af4f77bbd026a895", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b2f2df6dcc6cf942b4cb96f44167c2578264a3fea3528f813b0f5ed9b8bfd304d3efc00f03e0762d19eec61672259cd7df253f50df28eead27b47754b6523855", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3d40dc42c349cb2eae3e342f26ef2f21cfaec4d97d20cba6bcf86f5531364d46116ad2949d023199f3f02f63234626728ad090fa5384b2752dc236147b46462"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011322 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1ebe5a61ba44844921742c02ed91c9311c7377c8295810e84f2625a015eee53554a5de0ff5257448d4043bfac5383e17328ad66f17fb6f9439b7482498faaff", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e5070d73229101a53f8be721b094d088868f9344e7c885282c0c0eccbfb66dacd277938e1c572684dd4e8e9be16c64633bda61050c29b428af4f77bbd026a895", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b2f2df6dcc6cf942b4cb96f44167c2578264a3fea3528f813b0f5ed9b8bfd304d3efc00f03e0762d19eec61672259cd7df253f50df28eead27b47754b6523855", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3d40dc42c349cb2eae3e342f26ef2f21cfaec4d97d20cba6bcf86f5531364d46116ad2949d023199f3f02f63234626728ad090fa5384b2752dc236147b46462"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-28 17:41:16.249127) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:41:16.249445) ========= 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.8EN5Xi1oDJ/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.oxdhcLXHA9/Listeners TMPDIR=/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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.005024 ========= master_lag: 0.17 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:41:16.428260) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-28 17:41:16.428577) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-28 17:41:16.655515) ========= ========= Total master_lag: 0.75 =========