builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0277 starttime: 1493425909.46 results: success (0) buildid: 20170428170827 builduid: 4045495eb3ff48f382459aaa48aa5798 revision: b3b32894d9326d147238d9f071e1814ab3fdc850 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-28 17:31:49.457870) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-28 17:31:49.458429) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-28 17:31:49.458840) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-28 17:31:49.487768) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:31:49.488287) ========= 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.iSqJqZHh47/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.O0H0AO6WUZ/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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:31:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 54.5M=0s 2017-04-28 17:31:49 (54.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.140024 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:31:49.654065) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:31:49.654405) ========= 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.iSqJqZHh47/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.O0H0AO6WUZ/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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.042544 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:31:49.722607) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-28 17:31:49.723007) ========= 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.iSqJqZHh47/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.O0H0AO6WUZ/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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:31:49,832 truncating revision to first 12 chars 2017-04-28 17:31:49,832 Setting DEBUG logging. 2017-04-28 17:31:49,832 attempt 1/10 2017-04-28 17:31:49,832 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:31:50,546 unpacking tar archive at: mozilla-central-b3b32894d932/testing/mozharness/ program finished with exit code 0 elapsedTime=0.981946 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-28 17:31:50.730303) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-28 17:31:50.730748) ========= script_repo_revision: b3b32894d9326d147238d9f071e1814ab3fdc850 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-28 17:31:50.731252) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-28 17:31:50.731565) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-28 17:31:50.753624) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-04-28 17:31:50.754022) ========= /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.iSqJqZHh47/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.O0H0AO6WUZ/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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:31:50 INFO - MultiFileLogger online at 20170428 17:31:50 in /builds/slave/test 17:31:50 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:31:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:31:50 INFO - {'append_to_log': False, 17:31:50 INFO - 'base_work_dir': '/builds/slave/test', 17:31:50 INFO - 'blob_upload_branch': 'Firefox', 17:31:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:31:50 INFO - 'branch': 'Firefox', 17:31:50 INFO - 'buildbot_json_path': 'buildprops.json', 17:31:50 INFO - 'config_files': ('talos/mac_config.py',), 17:31:50 INFO - 'default_actions': ('clobber', 17:31:50 INFO - 'read-buildbot-config', 17:31:50 INFO - 'download-and-extract', 17:31:50 INFO - 'populate-webroot', 17:31:50 INFO - 'create-virtualenv', 17:31:50 INFO - 'install', 17:31:50 INFO - 'run-tests'), 17:31:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:31:50 INFO - 'download_minidump_stackwalk': True, 17:31:50 INFO - 'download_symbols': 'ondemand', 17:31:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:31:50 INFO - 'tooltool.py': '/tools/tooltool.py', 17:31:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:31:50 INFO - '/tools/misc-python/virtualenv.py')}, 17:31:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:31:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:31:50 INFO - 'gecko_profile': False, 17:31:50 INFO - 'gecko_profile_interval': 0, 17:31:50 INFO - 'installer_path': 'installer.exe', 17:31:50 INFO - 'log_level': 'info', 17:31:50 INFO - 'log_name': 'talos', 17:31:50 INFO - 'log_to_console': True, 17:31:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:31:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:31:50 INFO - 'opt_config_files': (), 17:31:50 INFO - 'pip_index': False, 17:31:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:31:50 INFO - 'cmd': ('bash', 17:31:50 INFO - '-c', 17:31:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:31:50 INFO - 'enabled': True, 17:31:50 INFO - 'halt_on_failure': False, 17:31:50 INFO - 'name': 'check_screen_resolution'},), 17:31:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:31:50 INFO - 'cmd': ('bash', 17:31:50 INFO - '-c', 17:31:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:31:50 INFO - 'enabled': True, 17:31:50 INFO - 'halt_on_failure': False, 17:31:50 INFO - 'name': 'check_screen_resolution'},), 17:31:50 INFO - 'run_cmd_checks_enabled': True, 17:31:50 INFO - 'suite': 'dromaeojs-e10s', 17:31:50 INFO - 'system_bits': '32', 17:31:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:31:50 INFO - 'title': 't-yosemite-r7-0277', 17:31:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:31:50 INFO - 'use_talos_json': True, 17:31:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:31:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:31:50 INFO - 'work_dir': 'build'} 17:31:50 INFO - [mozharness: 2017-04-29 00:31:50.968097Z] Running clobber step. 17:31:50 INFO - Running pre-action listener: _resource_record_pre_action 17:31:50 INFO - Running main action method: clobber 17:31:50 INFO - rmtree: /builds/slave/test/build 17:31:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:31:53 INFO - Running post-action listener: _resource_record_post_action 17:31:53 INFO - [mozharness: 2017-04-29 00:31:53.538186Z] Finished clobber step (success) 17:31:53 INFO - [mozharness: 2017-04-29 00:31:53.538351Z] Running read-buildbot-config step. 17:31:53 INFO - Running pre-action listener: _resource_record_pre_action 17:31:53 INFO - Running main action method: read_buildbot_config 17:31:53 INFO - Using buildbot properties: 17:31:53 INFO - { 17:31:53 INFO - "project": "", 17:31:53 INFO - "product": "firefox", 17:31:53 INFO - "script_repo_revision": "production", 17:31:53 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 17:31:53 INFO - "repository": "", 17:31:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 17:31:53 INFO - "buildid": "20170428170827", 17:31:53 INFO - "stage_platform": "macosx64", 17:31:53 INFO - "basedir": "/builds/slave/test", 17:31:53 INFO - "buildnumber": 53, 17:31:53 INFO - "platform": "yosemite_r7", 17:31:53 INFO - "mozci_request": { 17:31:53 INFO - "priority": 0, 17:31:53 INFO - "type": "trigger_all_talos_jobs", 17:31:53 INFO - "times": 6 17:31:53 INFO - }, 17:31:53 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 17:31:53 INFO - "slavebuilddir": "test", 17:31:53 INFO - "branch": "mozilla-central", 17:31:53 INFO - "revision": "b3b32894d9326d147238d9f071e1814ab3fdc850", 17:31:53 INFO - "slavename": "t-yosemite-r7-0277", 17:31:53 INFO - "builduid": "4045495eb3ff48f382459aaa48aa5798", 17:31:53 INFO - "repo_path": "mozilla-central" 17:31:53 INFO - } 17:31:53 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg. 17:31:53 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:31:53 INFO - Running post-action listener: _resource_record_post_action 17:31:53 INFO - [mozharness: 2017-04-29 00:31:53.542306Z] Finished read-buildbot-config step (success) 17:31:53 INFO - [mozharness: 2017-04-29 00:31:53.542419Z] Running download-and-extract step. 17:31:53 INFO - Running pre-action listener: _resource_record_pre_action 17:31:53 INFO - Running main action method: download_and_extract 17:31:53 INFO - mkdir: /builds/slave/test/build/tests 17:31:53 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:31:53 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.test_packages.json 17:31:53 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:31:53 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:31:53 INFO - Downloaded 1325 bytes. 17:31:53 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:31:53 INFO - Using the following test package requirements: 17:31:53 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:31:53 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 17:31:53 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:31:53 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:31:53 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 17:31:53 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:31:53 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 17:31:53 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:31:53 INFO - u'jsshell-mac.zip'], 17:31:53 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:31:53 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 17:31:53 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:31:53 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:31:53 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 17:31:53 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:31:53 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 17:31:53 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:31:53 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 17:31:53 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:31:53 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 17:31:53 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:31:53 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:31:53 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:31:53 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:31:55 INFO - Content-Length response header: 52698163 17:31:55 INFO - Bytes received: 52698163 17:32:11 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:32:11 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:32:11 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:32:11 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:32:12 INFO - Content-Length response header: 52698163 17:32:12 INFO - Bytes received: 52698163 17:32:28 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:32:28 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:32:28 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:32:28 INFO - Content-Length response header: 14408809 17:32:28 INFO - Bytes received: 14408809 17:32:28 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:32:28 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg 17:32:28 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:32:28 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:32:30 INFO - Downloaded 71219323 bytes. 17:32:30 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:32:30 INFO - mkdir: /builds/slave/test/properties 17:32:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:32:30 INFO - Writing to file /builds/slave/test/properties/build_url 17:32:30 INFO - Contents: 17:32:30 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg 17:32:30 INFO - Running post-action listener: _resource_record_post_action 17:32:30 INFO - Running post-action listener: set_extra_try_arguments 17:32:30 INFO - [mozharness: 2017-04-29 00:32:30.751645Z] Finished download-and-extract step (success) 17:32:30 INFO - [mozharness: 2017-04-29 00:32:30.751790Z] Running populate-webroot step. 17:32:30 INFO - Running pre-action listener: _resource_record_pre_action 17:32:30 INFO - Running main action method: populate_webroot 17:32:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:32:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:32:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:32:30 INFO - '--tppagecycles', 17:32:30 INFO - '7'], 17:32:30 INFO - 'tests': ['tsvgm']}}, 17:32:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:32:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:32:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:32:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:32:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:32:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:32:30 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:32:30 INFO - 'talos_options': ['--disable-e10s'], 17:32:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:32:30 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:32:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:32:30 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:32:30 INFO - 'talos_options': ['--disable-e10s'], 17:32:30 INFO - 'tests': ['damp', 'tps']}, 17:32:30 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:32:30 INFO - 'tests': ['damp', 'tps']}, 17:32:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:32:30 INFO - 'tests': ['dromaeo_dom']}, 17:32:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:32:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:32:30 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 17:32:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:32:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:32:30 INFO - 'tests': ['a11yr', 17:32:30 INFO - 'ts_paint', 17:32:30 INFO - 'tpaint', 17:32:30 INFO - 'sessionrestore', 17:32:30 INFO - 'sessionrestore_no_auto_restore', 17:32:30 INFO - 'tabpaint']}, 17:32:30 INFO - 'other-e10s': {'tests': ['a11yr', 17:32:30 INFO - 'ts_paint', 17:32:30 INFO - 'tpaint', 17:32:30 INFO - 'sessionrestore', 17:32:30 INFO - 'sessionrestore_no_auto_restore', 17:32:30 INFO - 'tabpaint']}, 17:32:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:32:30 INFO - 'ts_paint', 17:32:30 INFO - 'tpaint', 17:32:30 INFO - 'sessionrestore', 17:32:30 INFO - 'sessionrestore_no_auto_restore', 17:32:30 INFO - 'tabpaint']}, 17:32:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:32:30 INFO - 'ts_paint', 17:32:30 INFO - 'tpaint', 17:32:30 INFO - 'sessionrestore', 17:32:30 INFO - 'sessionrestore_no_auto_restore', 17:32:30 INFO - 'tabpaint']}, 17:32:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:32:30 INFO - 'tests': ['a11yr', 17:32:30 INFO - 'ts_paint', 17:32:30 INFO - 'tpaint', 17:32:30 INFO - 'sessionrestore', 17:32:30 INFO - 'sessionrestore_no_auto_restore', 17:32:30 INFO - 'tabpaint']}, 17:32:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:32:30 INFO - 'tests': ['a11yr', 17:32:30 INFO - 'ts_paint', 17:32:30 INFO - 'tpaint', 17:32:30 INFO - 'sessionrestore', 17:32:30 INFO - 'sessionrestore_no_auto_restore', 17:32:30 INFO - 'tabpaint']}, 17:32:30 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 17:32:30 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 17:32:30 INFO - 'bloom_basic_ref']}, 17:32:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:32:30 INFO - 'tests': ['tsvgx', 17:32:30 INFO - 'tsvgr_opacity', 17:32:30 INFO - 'tart', 17:32:30 INFO - 'tscrollx', 17:32:30 INFO - 'cart', 17:32:30 INFO - 'tsvg_static']}, 17:32:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:32:30 INFO - 'tsvgr_opacity', 17:32:30 INFO - 'tart', 17:32:30 INFO - 'tscrollx', 17:32:30 INFO - 'cart', 17:32:30 INFO - 'tsvg_static']}, 17:32:30 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:32:30 INFO - 'talos_options': ['--disable-e10s'], 17:32:30 INFO - 'tests': ['tp5o']}, 17:32:30 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:32:30 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:32:30 INFO - 'talos_options': ['--disable-e10s', 17:32:30 INFO - '--xperf_path', 17:32:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:32:30 INFO - 'tests': ['tp5n']}, 17:32:30 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:32:30 INFO - 'talos_options': ['--xperf_path', 17:32:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:32:30 INFO - 'tests': ['tp5n']}}, 17:32:30 INFO - 'talos.zip': {'path': '', 17:32:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:32:30 INFO - Running post-action listener: _resource_record_post_action 17:32:30 INFO - [mozharness: 2017-04-29 00:32:30.770840Z] Finished populate-webroot step (success) 17:32:30 INFO - [mozharness: 2017-04-29 00:32:30.770949Z] Running create-virtualenv step. 17:32:30 INFO - Running pre-action listener: _resource_record_pre_action 17:32:30 INFO - Running main action method: create_virtualenv 17:32:30 INFO - Creating virtualenv /builds/slave/test/build/venv 17:32:30 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:32:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:32:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:32:30 INFO - Using real prefix '/tools/python27' 17:32:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:32:31 INFO - Installing distribute.............................................................................................................................................................................................done. 17:32:34 INFO - Installing pip.................done. 17:32:34 INFO - Return code: 0 17:32:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:32:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:32:34 INFO - Reading from file tmpfile_stdout 17:32:34 INFO - Output received: 17:32:34 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:32:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:32:34 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:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:34 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:32:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:34 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 0x10573ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4e95846a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105518d30>, '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 0x10543edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10550dbe8>, '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.iSqJqZHh47/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.O0H0AO6WUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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:32:34 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:32:34 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:32:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iSqJqZHh47/Render', 17:32:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:32:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:32:34 INFO - 'HOME': '/Users/cltbld', 17:32:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:32:34 INFO - 'LOGNAME': 'cltbld', 17:32:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:34 INFO - 'MOZ_NO_REMOTE': '1', 17:32:34 INFO - 'NO_EM_RESTART': '1', 17:32:34 INFO - 'PAGER': '/bin/cat', 17:32:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:32:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:34 INFO - 'PWD': '/builds/slave/test', 17:32:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:32:34 INFO - 'SHELL': '/bin/bash', 17:32:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O0H0AO6WUZ/Listeners', 17:32:34 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 17:32:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:32:34 INFO - 'USER': 'cltbld', 17:32:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:32:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:32:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:34 INFO - 'XPC_FLAGS': '0x0', 17:32:34 INFO - 'XPC_SERVICE_NAME': '0', 17:32:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:32:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:35 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:32:35 INFO - Cleaning up... 17:32:35 INFO - Return code: 0 17:32:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:32:35 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:32:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:35 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:32:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:35 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 0x10573ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4e95846a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105518d30>, '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 0x10543edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10550dbe8>, '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.iSqJqZHh47/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.O0H0AO6WUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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:32:35 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:32:35 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:32:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iSqJqZHh47/Render', 17:32:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:32:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:32:35 INFO - 'HOME': '/Users/cltbld', 17:32:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:32:35 INFO - 'LOGNAME': 'cltbld', 17:32:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:35 INFO - 'MOZ_NO_REMOTE': '1', 17:32:35 INFO - 'NO_EM_RESTART': '1', 17:32:35 INFO - 'PAGER': '/bin/cat', 17:32:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:32:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:35 INFO - 'PWD': '/builds/slave/test', 17:32:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:32:35 INFO - 'SHELL': '/bin/bash', 17:32:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O0H0AO6WUZ/Listeners', 17:32:35 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 17:32:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:32:35 INFO - 'USER': 'cltbld', 17:32:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:32:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:32:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:35 INFO - 'XPC_FLAGS': '0x0', 17:32:35 INFO - 'XPC_SERVICE_NAME': '0', 17:32:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:32:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:35 INFO - Downloading/unpacking psutil>=3.1.1 17:32:35 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:32:35 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:32:35 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:32:35 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:32:35 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:32:35 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:32:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:32:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:32:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:32:38 INFO - Installing collected packages: psutil 17:32:38 INFO - Running setup.py install for psutil 17:32:38 INFO - building 'psutil._psutil_osx' extension 17:32:38 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:32:39 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:32:39 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:32:39 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:32:39 INFO - building 'psutil._psutil_posix' extension 17:32:39 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:32:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:32:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:32:39 INFO - ^ 17:32:39 INFO - 1 warning generated. 17:32:39 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:32:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:32:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:32:39 INFO - Successfully installed psutil 17:32:39 INFO - Cleaning up... 17:32:39 INFO - Return code: 0 17:32:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:32:39 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:32:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:39 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:32:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:39 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 0x10573ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4e95846a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105518d30>, '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 0x10543edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10550dbe8>, '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.iSqJqZHh47/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.O0H0AO6WUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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:32:39 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:32:39 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:32:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iSqJqZHh47/Render', 17:32:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:32:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:32:39 INFO - 'HOME': '/Users/cltbld', 17:32:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:32:39 INFO - 'LOGNAME': 'cltbld', 17:32:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:39 INFO - 'MOZ_NO_REMOTE': '1', 17:32:39 INFO - 'NO_EM_RESTART': '1', 17:32:39 INFO - 'PAGER': '/bin/cat', 17:32:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:32:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:39 INFO - 'PWD': '/builds/slave/test', 17:32:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:32:39 INFO - 'SHELL': '/bin/bash', 17:32:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O0H0AO6WUZ/Listeners', 17:32:39 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 17:32:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:32:39 INFO - 'USER': 'cltbld', 17:32:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:32:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:32:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:39 INFO - 'XPC_FLAGS': '0x0', 17:32:39 INFO - 'XPC_SERVICE_NAME': '0', 17:32:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:32:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:32:40 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:32:40 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:32:40 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:32:40 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:32:40 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:32:40 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:32:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:32:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:32:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:32:42 INFO - Installing collected packages: mozsystemmonitor 17:32:42 INFO - Running setup.py install for mozsystemmonitor 17:32:43 INFO - Successfully installed mozsystemmonitor 17:32:43 INFO - Cleaning up... 17:32:43 INFO - Return code: 0 17:32:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:32: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:32:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32: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:32:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32: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', '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 0x10573ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4e95846a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105518d30>, '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 0x10543edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10550dbe8>, '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.iSqJqZHh47/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.O0H0AO6WUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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:32: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:32: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 jsonschema==2.5.1 17:32:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iSqJqZHh47/Render', 17:32:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:32:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:32:43 INFO - 'HOME': '/Users/cltbld', 17:32:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:32:43 INFO - 'LOGNAME': 'cltbld', 17:32:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:43 INFO - 'MOZ_NO_REMOTE': '1', 17:32:43 INFO - 'NO_EM_RESTART': '1', 17:32:43 INFO - 'PAGER': '/bin/cat', 17:32:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:32:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:43 INFO - 'PWD': '/builds/slave/test', 17:32:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:32:43 INFO - 'SHELL': '/bin/bash', 17:32:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O0H0AO6WUZ/Listeners', 17:32:43 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 17:32:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:32:43 INFO - 'USER': 'cltbld', 17:32:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:32:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:32:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:43 INFO - 'XPC_FLAGS': '0x0', 17:32:43 INFO - 'XPC_SERVICE_NAME': '0', 17:32:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:32:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:43 INFO - Downloading/unpacking jsonschema==2.5.1 17:32: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:32: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:32: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:32: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:32: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:32: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:32:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:32:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:32:46 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:32:46 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:32:46 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:32:46 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:32:46 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:32:46 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:32:46 INFO - Downloading functools32-3.2.3-2.tar.gz 17:32:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:32:46 INFO - warning: no files found matching '*.txt' 17:32:46 INFO - no previously-included directories found matching 'build' 17:32:46 INFO - no previously-included directories found matching 'dist' 17:32:46 INFO - no previously-included directories found matching '.git*' 17:32:46 INFO - Installing collected packages: jsonschema, functools32 17:32:46 INFO - Running setup.py install for functools32 17:32:46 INFO - warning: no files found matching '*.txt' 17:32:46 INFO - no previously-included directories found matching 'build' 17:32:46 INFO - no previously-included directories found matching 'dist' 17:32:46 INFO - no previously-included directories found matching '.git*' 17:32:46 INFO - Successfully installed jsonschema functools32 17:32:46 INFO - Cleaning up... 17:32:46 INFO - Return code: 0 17:32:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:32:46 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:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:47 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:32:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:47 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 0x10573ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4e95846a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105518d30>, '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 0x10543edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10550dbe8>, '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.iSqJqZHh47/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.O0H0AO6WUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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:32:47 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:32:47 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:32:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iSqJqZHh47/Render', 17:32:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:32:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:32:47 INFO - 'HOME': '/Users/cltbld', 17:32:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:32:47 INFO - 'LOGNAME': 'cltbld', 17:32:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:47 INFO - 'MOZ_NO_REMOTE': '1', 17:32:47 INFO - 'NO_EM_RESTART': '1', 17:32:47 INFO - 'PAGER': '/bin/cat', 17:32:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:32:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:47 INFO - 'PWD': '/builds/slave/test', 17:32:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:32:47 INFO - 'SHELL': '/bin/bash', 17:32:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O0H0AO6WUZ/Listeners', 17:32:47 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 17:32:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:32:47 INFO - 'USER': 'cltbld', 17:32:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:32:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:32:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:47 INFO - 'XPC_FLAGS': '0x0', 17:32:47 INFO - 'XPC_SERVICE_NAME': '0', 17:32:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:32:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:32:47 INFO - Cleaning up... 17:32:47 INFO - Return code: 0 17:32:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:32:47 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:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:47 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:32:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32: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', '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 0x10573ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4e95846a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105518d30>, '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 0x10543edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10550dbe8>, '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.iSqJqZHh47/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.O0H0AO6WUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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:32: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:32: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 blobuploader==1.2.4 17:32:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iSqJqZHh47/Render', 17:32:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:32:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:32:48 INFO - 'HOME': '/Users/cltbld', 17:32:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:32:48 INFO - 'LOGNAME': 'cltbld', 17:32:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:48 INFO - 'MOZ_NO_REMOTE': '1', 17:32:48 INFO - 'NO_EM_RESTART': '1', 17:32:48 INFO - 'PAGER': '/bin/cat', 17:32:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:32:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:48 INFO - 'PWD': '/builds/slave/test', 17:32:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:32:48 INFO - 'SHELL': '/bin/bash', 17:32:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O0H0AO6WUZ/Listeners', 17:32:48 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 17:32:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:32:48 INFO - 'USER': 'cltbld', 17:32:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:32:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:32:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:48 INFO - 'XPC_FLAGS': '0x0', 17:32:48 INFO - 'XPC_SERVICE_NAME': '0', 17:32:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:32:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:48 INFO - Downloading/unpacking blobuploader==1.2.4 17:32: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:32: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:32: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:32: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:32: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:32: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:32:51 INFO - Downloading blobuploader-1.2.4.tar.gz 17:32:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:32:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:32: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:32: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:32: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:32: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:32: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:32: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:32:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:32:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:32:52 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:32:52 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:32:52 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:32:52 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:32:52 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:32:52 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:32:52 INFO - Downloading docopt-0.6.1.tar.gz 17:32:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:32:53 INFO - Installing collected packages: blobuploader, requests, docopt 17:32:53 INFO - Running setup.py install for blobuploader 17:32:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:32:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:32:53 INFO - Running setup.py install for requests 17:32:53 INFO - Running setup.py install for docopt 17:32:53 INFO - Successfully installed blobuploader requests docopt 17:32:53 INFO - Cleaning up... 17:32:53 INFO - Return code: 0 17:32:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:32:53 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:32:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:53 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:32:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:53 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 0x10573ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4e95846a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105518d30>, '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 0x10543edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10550dbe8>, '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.iSqJqZHh47/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.O0H0AO6WUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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:32:53 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:32:53 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:32:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iSqJqZHh47/Render', 17:32:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:32:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:32:53 INFO - 'HOME': '/Users/cltbld', 17:32:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:32:53 INFO - 'LOGNAME': 'cltbld', 17:32:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:53 INFO - 'MOZ_NO_REMOTE': '1', 17:32:53 INFO - 'NO_EM_RESTART': '1', 17:32:53 INFO - 'PAGER': '/bin/cat', 17:32:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:32:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:53 INFO - 'PWD': '/builds/slave/test', 17:32:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:32:53 INFO - 'SHELL': '/bin/bash', 17:32:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O0H0AO6WUZ/Listeners', 17:32:53 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 17:32:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:32:53 INFO - 'USER': 'cltbld', 17:32:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:32:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:32:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:53 INFO - 'XPC_FLAGS': '0x0', 17:32:53 INFO - 'XPC_SERVICE_NAME': '0', 17:32:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:32:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:32:54 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-7QMwop-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:32:54 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-vmVpIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:32:54 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-ynWxQq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:32:54 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-sSoQLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:32:54 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-8J9UdH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:32:54 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-PRPDS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:32:54 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-iSPCRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:32:54 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-cFwrLb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:32:55 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-6LeF6C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:32:55 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-gADEFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:32:55 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-p6fBqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:32:55 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-f5c1_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:32:55 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-mXEp2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:32:55 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-GoBnMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:32:55 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-JLBQhR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:32:55 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-yZo83q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:32:55 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-OX2bfN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:32:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:32:55 INFO - Running setup.py install for manifestparser 17:32:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:32:56 INFO - Running setup.py install for mozcrash 17:32:56 INFO - Running setup.py install for mozdebug 17:32:56 INFO - Running setup.py install for mozdevice 17:32:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:32:56 INFO - Running setup.py install for mozfile 17:32:56 INFO - Running setup.py install for mozhttpd 17:32:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:32:56 INFO - Running setup.py install for mozinfo 17:32:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:32:56 INFO - Running setup.py install for mozInstall 17:32:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:32:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:32:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:32:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:32:57 INFO - Running setup.py install for mozleak 17:32:57 INFO - Running setup.py install for mozlog 17:32:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:32:57 INFO - Running setup.py install for moznetwork 17:32:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:32:57 INFO - Running setup.py install for mozprocess 17:32:57 INFO - Running setup.py install for mozprofile 17:32:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:32:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:32:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:32:57 INFO - Running setup.py install for mozrunner 17:32:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:32:58 INFO - Running setup.py install for mozscreenshot 17:32:58 INFO - Running setup.py install for moztest 17:32:58 INFO - Running setup.py install for mozversion 17:32:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:32:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:32:58 INFO - Cleaning up... 17:32:58 INFO - Return code: 0 17:32:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:32:58 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:32:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:58 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:32:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:58 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 0x10573ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4e95846a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105518d30>, '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 0x10543edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10550dbe8>, '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.iSqJqZHh47/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.O0H0AO6WUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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:32:58 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:32:58 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:32:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iSqJqZHh47/Render', 17:32:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:32:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:32:58 INFO - 'HOME': '/Users/cltbld', 17:32:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:32:58 INFO - 'LOGNAME': 'cltbld', 17:32:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:58 INFO - 'MOZ_NO_REMOTE': '1', 17:32:58 INFO - 'NO_EM_RESTART': '1', 17:32:58 INFO - 'PAGER': '/bin/cat', 17:32:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:32:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:58 INFO - 'PWD': '/builds/slave/test', 17:32:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:32:58 INFO - 'SHELL': '/bin/bash', 17:32:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O0H0AO6WUZ/Listeners', 17:32:58 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 17:32:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:32:58 INFO - 'USER': 'cltbld', 17:32:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:32:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:32:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:58 INFO - 'XPC_FLAGS': '0x0', 17:32:58 INFO - 'XPC_SERVICE_NAME': '0', 17:32:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:32:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:32:58 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-nCqrNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:32:58 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:32:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:32:58 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-yB0OIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:32:58 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:32:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:32:58 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-Ubrne8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:32:59 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-lCJPc4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:32:59 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:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:32:59 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-vPFrRb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:32:59 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:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:32:59 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-O1dhkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:32:59 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:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:32:59 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-zQfKUo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:32:59 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:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:32:59 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-UWPdMU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:32:59 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:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:32:59 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-bkdTYd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:32:59 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:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:32:59 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-rPghBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:32:59 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:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:32:59 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-Cusvim-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:32:59 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:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:32:59 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-5FMMoK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:33:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:33:00 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-sUKlZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:33:00 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:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:33:00 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-W76llu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:33:00 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:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:33:00 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-abvdEF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:33:00 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:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:33:00 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-FudbMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:33:00 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:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:33:00 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-yi68_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:33:00 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:33:00 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:33:00 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:33:00 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:33:00 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:33:00 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:33:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:33: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:33: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:33: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:33: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:33: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:33: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:33:03 INFO - Downloading blessings-1.6.tar.gz 17:33:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:33:03 INFO - Installing collected packages: blessings 17:33:03 INFO - Running setup.py install for blessings 17:33:03 INFO - Successfully installed blessings 17:33:03 INFO - Cleaning up... 17:33:03 INFO - Return code: 0 17:33:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:33:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:33:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:33:03 INFO - Reading from file tmpfile_stdout 17:33:03 INFO - Current package versions: 17:33:03 INFO - blessings == 1.6 17:33:03 INFO - blobuploader == 1.2.4 17:33:03 INFO - docopt == 0.6.1 17:33:03 INFO - functools32 == 3.2.3-2 17:33:03 INFO - jsonschema == 2.5.1 17:33:03 INFO - manifestparser == 1.1 17:33:03 INFO - mozInstall == 1.12 17:33:03 INFO - mozcrash == 1.0 17:33:03 INFO - mozdebug == 0.1 17:33:03 INFO - mozdevice == 0.50 17:33:03 INFO - mozfile == 1.2 17:33:03 INFO - mozhttpd == 0.7 17:33:03 INFO - mozinfo == 0.9 17:33:03 INFO - mozleak == 0.1 17:33:03 INFO - mozlog == 3.4 17:33:03 INFO - moznetwork == 0.27 17:33:03 INFO - mozprocess == 0.25 17:33:03 INFO - mozprofile == 0.28 17:33:03 INFO - mozrunner == 6.13 17:33:03 INFO - mozscreenshot == 0.1 17:33:03 INFO - mozsystemmonitor == 0.3 17:33:03 INFO - moztest == 0.8 17:33:03 INFO - mozversion == 1.4 17:33:03 INFO - psutil == 3.1.1 17:33:03 INFO - requests == 1.2.3 17:33:03 INFO - wsgiref == 0.1.2 17:33:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:33:03 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:33:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:33:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33:03 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:33:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:33:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10573ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4e95846a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105518d30>, '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 0x10543edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10550dbe8>, '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.iSqJqZHh47/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.O0H0AO6WUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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:33:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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:33:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.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:33:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iSqJqZHh47/Render', 17:33:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:33:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:33:03 INFO - 'HOME': '/Users/cltbld', 17:33:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:33:03 INFO - 'LOGNAME': 'cltbld', 17:33:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:33:03 INFO - 'MOZ_NO_REMOTE': '1', 17:33:03 INFO - 'NO_EM_RESTART': '1', 17:33:03 INFO - 'PAGER': '/bin/cat', 17:33:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:33:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:33:03 INFO - 'PWD': '/builds/slave/test', 17:33:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:33:03 INFO - 'SHELL': '/bin/bash', 17:33:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O0H0AO6WUZ/Listeners', 17:33:03 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 17:33:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:33:03 INFO - 'USER': 'cltbld', 17:33:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:33:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:33:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:33:03 INFO - 'XPC_FLAGS': '0x0', 17:33:03 INFO - 'XPC_SERVICE_NAME': '0', 17:33:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:33:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:33:04 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:33:04 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:33:04 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:33:04 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:33:04 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:33:04 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:33:04 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:33:04 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:33:04 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:33:04 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:33:04 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:33:04 INFO - Cleaning up... 17:33:04 INFO - Return code: 0 17:33:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:33:04 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:33:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:33:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33:04 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:33:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:33:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33:04 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 0x10573ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4e95846a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105518d30>, '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 0x10543edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10550dbe8>, '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.iSqJqZHh47/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.O0H0AO6WUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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:33:04 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:33:04 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:33:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iSqJqZHh47/Render', 17:33:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:33:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:33:04 INFO - 'HOME': '/Users/cltbld', 17:33:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:33:04 INFO - 'LOGNAME': 'cltbld', 17:33:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:33:04 INFO - 'MOZ_NO_REMOTE': '1', 17:33:04 INFO - 'NO_EM_RESTART': '1', 17:33:04 INFO - 'PAGER': '/bin/cat', 17:33:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:33:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:33:04 INFO - 'PWD': '/builds/slave/test', 17:33:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:33:04 INFO - 'SHELL': '/bin/bash', 17:33:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O0H0AO6WUZ/Listeners', 17:33:04 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 17:33:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:33:04 INFO - 'USER': 'cltbld', 17:33:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:33:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:33:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:33:04 INFO - 'XPC_FLAGS': '0x0', 17:33:04 INFO - 'XPC_SERVICE_NAME': '0', 17:33:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:33:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:33:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:33:04 INFO - Cleaning up... 17:33:04 INFO - Return code: 0 17:33:04 INFO - Running post-action listener: _resource_record_post_action 17:33:04 INFO - Running post-action listener: _start_resource_monitoring 17:33:04 INFO - Starting resource monitoring. 17:33:04 INFO - [mozharness: 2017-04-29 00:33:04.426106Z] Finished create-virtualenv step (success) 17:33:04 INFO - [mozharness: 2017-04-29 00:33:04.427078Z] Running install step. 17:33:04 INFO - Running pre-action listener: _resource_record_pre_action 17:33:04 INFO - Running main action method: install 17:33:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:33:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:33:04 INFO - Reading from file tmpfile_stdout 17:33:04 INFO - Detecting whether we're running mozinstall >=1.0... 17:33:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:33:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:33:04 INFO - Reading from file tmpfile_stdout 17:33:04 INFO - Output received: 17:33:04 INFO - Usage: mozinstall [options] installer 17:33:04 INFO - Options: 17:33:04 INFO - -h, --help show this help message and exit 17:33:04 INFO - -d DEST, --destination=DEST 17:33:04 INFO - Directory to install application into. [default: 17:33:04 INFO - "/builds/slave/test"] 17:33:04 INFO - --app=APP Application being installed. [default: firefox] 17:33:04 INFO - mkdir: /builds/slave/test/build/application 17:33:04 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:33:04 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:33:24 INFO - Reading from file tmpfile_stdout 17:33:24 INFO - Output received: 17:33:24 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:33:24 INFO - Running post-action listener: _resource_record_post_action 17:33:24 INFO - [mozharness: 2017-04-29 00:33:24.904231Z] Finished install step (success) 17:33:24 INFO - [mozharness: 2017-04-29 00:33:24.904363Z] Running run-tests step. 17:33:24 INFO - Running pre-action listener: _resource_record_pre_action 17:33:24 INFO - Running main action method: run_tests 17:33:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:33:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:33:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:33:24 INFO - 2017-04-28 17:33:24.918 screenresolution[1622:7370] starting screenresolution argv=screenresolution get 17:33:24 INFO - 2017-04-28 17:33:24.921 screenresolution[1622:7370] Display 0: 1600x1200x32@60 17:33:24 INFO - 2017-04-28 17:33:24.927 screenresolution[1623:7373] starting screenresolution argv=screenresolution list 17:33:24 INFO - Available Modes on Display 0 17:33:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:33:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:33:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:33:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:33:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:33:25 INFO - Intel Iris: 17:33:25 INFO - Chipset Model: Intel Iris 17:33:25 INFO - Type: GPU 17:33:25 INFO - Bus: Built-In 17:33:25 INFO - VRAM (Dynamic, Max): 1536 MB 17:33:25 INFO - Vendor: Intel (0x8086) 17:33:25 INFO - Device ID: 0x0a2e 17:33:25 INFO - Revision ID: 0x0009 17:33:25 INFO - Displays: 17:33:25 INFO - Display: 17:33:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:33:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:33:25 INFO - Display Serial Number: 1600x1200 60 17:33:25 INFO - Main Display: Yes 17:33:25 INFO - Mirror: Off 17:33:25 INFO - Online: Yes 17:33:25 INFO - Rotation: Supported 17:33:25 INFO - Return code: 0 17:33:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:33:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:33:25 INFO - Python 2.7.3 17:33:25 INFO - Return code: 0 17:33:25 INFO - grabbing minidump binary from tooltool 17:33:25 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:33:25 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 0x105518d30>, '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 0x10543edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10550dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:33:25 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:33:25 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:33:25 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:33:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:33:25 INFO - Return code: 0 17:33:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:33:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:33:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:33:25 INFO - ENV: RUST_BACKTRACE is now 1 17:33:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:33:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:33:25 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-0277', '--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:33:25 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-0277 --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:33:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iSqJqZHh47/Render', 17:33:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:33:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:33:25 INFO - 'HOME': '/Users/cltbld', 17:33:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:33:25 INFO - 'LOGNAME': 'cltbld', 17:33:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:33:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:33:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:33:25 INFO - 'MOZ_NO_REMOTE': '1', 17:33:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:33:25 INFO - 'NO_EM_RESTART': '1', 17:33:25 INFO - 'PAGER': '/bin/cat', 17:33:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:33:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:33:25 INFO - 'PWD': '/builds/slave/test', 17:33:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:33:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:33:25 INFO - 'RUST_BACKTRACE': '1', 17:33:25 INFO - 'SHELL': '/bin/bash', 17:33:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O0H0AO6WUZ/Listeners', 17:33:25 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 17:33:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:33:25 INFO - 'USER': 'cltbld', 17:33:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:33:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:33:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:33:25 INFO - 'XPC_FLAGS': '0x0', 17:33:25 INFO - 'XPC_SERVICE_NAME': '0', 17:33:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:33:25 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-0277', '--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:33:25 INFO - mozversion application_buildid: 20170428075320 17:33:25 INFO - mozversion application_changeset: b3b32894d9326d147238d9f071e1814ab3fdc850 17:33:25 INFO - mozversion application_display_name: Nightly 17:33:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:33:25 INFO - mozversion application_name: Firefox 17:33:25 INFO - mozversion application_remotingname: firefox 17:33:25 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:33:25 INFO - mozversion application_vendor: Mozilla 17:33:25 INFO - mozversion application_version: 55.0a1 17:33:25 INFO - mozversion platform_buildid: 20170428075320 17:33:25 INFO - mozversion platform_changeset: b3b32894d9326d147238d9f071e1814ab3fdc850 17:33:25 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:33:25 INFO - mozversion platform_version: 55.0a1 17:33:25 INFO - using testdate: 1493426005 17:33:25 INFO - actual date: 1493426005 17:33:25 INFO - starting webserver on 'localhost:49230' 17:33:25 INFO - SUITE-START | Running 2 tests 17:33:25 INFO - TEST-START | dromaeo_css 17:33:25 INFO - operating with platform_type : mac_ 17:33:25 INFO - Initialising browser for dromaeo_css test... 17:33:25 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/tmpGdBBqP/profile http://localhost:49230/getInfo.html) 17:33:27 INFO - PID 1633 | 2017-04-28 17:33:27.938 plugin-container[1635:7560] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 17:33:27 INFO - PID 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:33:27 INFO - PID 1633 | 2017-04-28 17:33:27.941 plugin-container[1635:7560] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 17:33:27 INFO - PID 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:33:27 INFO - PID 1633 | 2017-04-28 17:33:27.941 plugin-container[1635:7560] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:33:28 INFO - PID 1633 | __metrics Screen width/height:1600/1200 17:33:28 INFO - PID 1633 | colorDepth:24 17:33:28 INFO - PID 1633 | Browser inner width/height: 1024/689 17:33:28 INFO - PID 1633 | __metrics 17:33:28 ERROR - PID 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3424: TypeError: this.tabs is undefined 17:33:29 INFO - TEST-INFO | 1633: exit 0 17:33:29 INFO - Browser initialized. 17:33:29 INFO - Running cycle 1/1 for dromaeo_css test... 17:33:29 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/tmpGdBBqP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:33:31 INFO - PID 1636 | 2017-04-28 17:33:31.460 plugin-container[1637:7686] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x954b, name = 'com.apple.tsm.portname' 17:33:31 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:33:31 INFO - PID 1636 | 2017-04-28 17:33:31.461 plugin-container[1637:7686] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0f, name = 'com.apple.CFPasteboardClient' 17:33:31 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:33:31 INFO - PID 1636 | 2017-04-28 17:33:31.461 plugin-container[1637:7686] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:33:41 INFO - PID 1636 | RSS: Main: 251256832 17:33:41 INFO - PID 1636 | 17:36:45 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49230/tests/dromaeo/cssquery-ext.html) 17:36:47 INFO - PID 1636 | RSS: Main: 253984768 17:36:47 INFO - PID 1636 | 17:38:29 INFO - PID 1636 | ************************* 17:38:29 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 17:38:29 INFO - PID 1636 | 17:38:29 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:38:29 INFO - PID 1636 | Full stack: 17:38:29 INFO - PID 1636 | ************************* 17:38:29 INFO - PID 1636 | ************************* 17:38:29 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 17:38:29 INFO - PID 1636 | 17:38:29 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:38:29 INFO - PID 1636 | Full stack: 17:38:29 INFO - PID 1636 | ************************* 17:38:29 INFO - PID 1636 | ************************* 17:38:29 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 17:38:29 INFO - PID 1636 | 17:38:29 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:38:29 INFO - PID 1636 | Full stack: 17:38:29 INFO - PID 1636 | ************************* 17:38:29 INFO - PID 1636 | ************************* 17:38:29 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 17:38:29 INFO - PID 1636 | 17:38:29 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:38:29 INFO - PID 1636 | Full stack: 17:38:29 INFO - PID 1636 | ************************* 17:39:49 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-ext.html (next: http://localhost:49230/tests/dromaeo/cssquery-jquery.html) 17:39:49 INFO - PID 1636 | RSS: Main: 259260416 17:39:49 INFO - PID 1636 | 17:42:51 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49230/tests/dromaeo/cssquery-mootools.html) 17:42:52 INFO - PID 1636 | RSS: Main: 272261120 17:42:52 INFO - PID 1636 | 17:45:53 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49230/tests/dromaeo/cssquery-prototype.html) 17:45:54 INFO - PID 1636 | RSS: Main: 265424896 17:45:54 INFO - PID 1636 | 17:48:57 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49230/tests/dromaeo/cssquery-yui.html) 17:48:57 INFO - PID 1636 | RSS: Main: 273571840 17:48:57 INFO - PID 1636 | 17:51:59 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-yui.html (next: http://localhost:49230/tests/dromaeo/cssquery-dojo.html) 17:51:59 INFO - PID 1636 | RSS: Main: 269504512 17:51:59 INFO - PID 1636 | 17:51:59 INFO - PID 1636 | __start_tp_report 17:51:59 INFO - PID 1636 | _x_x_mozilla_page_load 17:51:59 INFO - PID 1636 | _x_x_mozilla_page_load_details 17:51:59 INFO - PID 1636 | |i|pagename|runs| 17:51:59 INFO - PID 1636 | |0;dojo.html;2975.2115689342327;2995.610570625818;3582.1402863312815;3628.927421451571;3633.309671162479;5796.275465566804;6038.5471089668345;6140.048292514662;6183.350748171439;6236.656983865885;6106.187656479749;6119.502553941423;6154.138420621117;6158.6612736299485;6204.627722336663;4459.464864216298;4682.08699303636;5265.447128051547;5397.676139431627;5404.891902161954;5166.354205724285;5527.2538207342095;5534.169874518814;5575.470330318613;5592.356878958931;5280.815171469018;5437.32033495813;5449.3460784706085;5521.337439507265;5526.06056970314;4869.366982292315;5087.643864929457;5346.278252435949;5402.621816472849;5420.7018613976215;4433.379326894237;5389.784408623689;5410.296661433988;5434.2935418395755;5442.428545002757;5973.581849270553;6245.687715614201;6253.343398943137;6269.655168965705;6295.653739044351;6401;6456.6771661417215;6564.868702625969;6635.635040072794;6677.499187560904;6713.664316784141;6740.460763138986;6760.459163266976;6798.864022719518;6807.829804254884;5204.219367094928;6238.875222495615;6327.588706734047;6348.143000694893;6348.333424990402;5406.243125962368;6707.228668703064;6814.727410903607;6905.309469053155;6939.132608423947;6425.871482570323;6543.411093001652;6549.574277671936;6560.048792924999;6599.70301336441;6681.465482761368;6684.465242780566;6691.130153080166;6722.932770672329;6746.628935408599;4811.398575178103;6570.737170513222;6598.37315455031;6599.60402375859;6631.137952066297;6377.139086223393;6478.352164783484;6539.051837483538;6594.07682924391;6629.602223866584;5250.343707036621;5263.210518422191;5283.841484755463;5290.841274761763;5310.150375939831;4552.499225085238;4689.226277664147;4693.78877950493;4717.363155973968;4722.834700785456;5042.924356134661;6044.911915855188;6246.6876656167005;6265.122882796412;6289.339619339995;6666.0667506549125;6709.094272273278;6741.59550426976;6763;6780.288069752605;6378.521610879203;6382.840428989312;6384.042393640898;6408.455281301049;6413.743450262031;5700;5818.272715910512;5890.8821823563285;5893.616914900518;5903.734331955073;6126.23422072241;6580.243272023778;6769.796906092924;6815;6973.302669732986;6487.54587178893;6578.5395022347975;6645.9003114954285;6734.090897728744;6762.8647427052165;6852.349026842442;6940.09778728762;6974.930250697428;6976.616286104313;6984.615846128511;6638.369354911276;6652.767153149616;6658.267590565131;6669;6690.096836927147;6025.276966764016;6240.251169859464;6523.836904077436;6587.934120658732;6608.801735947929;6500.869982600227;6503.674816259263;6527.314631963765;6586.70359833799;6595.472362210916;6551.574147680385;6558.04908288286;6558.1802274715665;6559.704813283318;6572.76995305162;5095.949040509548;6251.499880009679;6332.0501924709815;6382.255969627187;6429.51778616615;6406.839829004312;6419.454346380423;6498;6528.608283503005;6549.901751473636;6370.904436433365;6372.203474565679;6388.105665206967;6392.552521323462;6400.103985441949 17:51:59 INFO - PID 1636 | |1;ext.html;761.0866959648422;823.0452674897118;1067.8558394616725;1083.5611577311188;1144.107596075061;36675.81662091689;37614.8119259404;37725;37748.622513774855;37870.863874083785;37157.51369945199;37542.249155016885;37550.4367434488;37571.624283757155;37671;7725.61371931404;7891.0925243597;8042.557659328734;8684.8697269541;8689;8582.270507006911;8713.259372953275;8723.520206388646;8735.781605459852;8744.300455963508;8705.129487051308;8733.34499912506;8754.430961987482;8758.56207189638;8765.474071555695;8673.739787806344;8685.131486851264;8716.694915677921;8721.171488708562;8733.912660873373;8421.578921053922;8447.32421406286;8451.619677114544;8485.745427637181;8518.488890666518;37159.81420092909;37361.439578406345;37394.813025934694;37460;37515.62484375176;3146.6695996920453;3637.781733096023;3672.6143754905884;3673.210259794157;3674.375356189454;30348.241293967607;30543.236419089477;30557.694423055706;30619.540706889413;30660.540091898638;3141.7486601072087;3175.3490534440384;3175.9364812703616;3177.5869137012273;3189.6650851660706;300403;301615;301829;302772;304800;39868.60131398649;40389.79805100956;47859.521404785504;48109.75945120322;49427.258591121164;50341.74829125831;50431;50839.74580127076;50976;51020.23469647958;11994.940025299818;14302.427902883976;14334.354954027094;14390.352434140488;14399.856001440061;11456.54173833054;11564.074874010059;11580.36308003068;11596.94201528987;11765.705857353463;13585.932070339584;13604;13621.455141794217;13678.384472698752;13773.14062601556;5032.446430892599;5911.142884281755;5948.67282299469;5966.224390829251;5989.55078369124;896.7623579751371;979.0747743382435;1018.6689325969089;1027.984580231297;1080.36258607422;23790.167344142872;24404.14585489535;24440.633390499155;24459.755402446102;24522.14172503954;967.5307475874196;1123.741539445932;1135.3868910788246;1154.0363796230074;1161.651504548649;12038;12561.434735436927;12574.685632859251;12586.37068146589;12613.936930315289;322712;324236;324276;324449;324783;54394;54461;54753;54857.72571137119;54933.725331373884;1484.5694748523024;1524.3521503361028;1544.8300686924429;1552.1773460066183;1558.4701201591286;15813.920930395274;19888.50278743043;21125.894370528666;21340.03969821393;22746;30454;36913.63086369102;37763.62236377711;37812.2437551242;37857.05357366088;238901;279570;284648;285889;286292;33480.66077356976;33880.66119338874;34856.47715284222;34863.47704784379;35031;42851;43325.133497330506;44709.55290447184;44760.88097797581;44826;26619.467610648066;29494.262643434085;29603.963861264754;30408.84795576008;30772;26111.869440652674;28097;30503.38993220079;31096;31560.210994725316;36800.63199368079;36937;37023.07442313856;37059;37156;29635.962741303952;30095.247618809706;31288;31578.894738684037;32331.030069097025;21416.464588385414;24295.392615184763;24297;24755.381115472257;24910.501789964463 17:51:59 INFO - PID 1636 | |2;jquery.html;401.92765302245596;428.9785510724463;434.4916447756127;435.87141793171;463.6661603645374;15963.361465541364;21865.234716784915;22574.20990265337;22685;22767.77232227677;19530.41408757736;22609;23616.881915590442;23765.5246895062;23997.88001059992;5955.464008239247;6172.197614310156;6236.158118653969;6236.781712640059;6236.906446403307;5828.184054232411;6096;6115.847103822418;6138.7237574309265;6151.569390142692;5870.677112758796;6062.757489700429;6095.05526643371;6103.725332360033;6106.8473288167925;5955.493783028448;6012;6035.396460353952;6039.4262545058145;6077.361877002917;4643.674942754009;5088.821891233789;5403;5419.485148910847;5453.727313634303;26319.052142178636;26492.337691557674;26532.46935061288;26775.732242677517;26879.32801679954;2177.1617927098023;2246.3597874605657;2249.280230326296;2251.8648881067024;2255.289583781104;17997.820021799744;18224.179911903997;18267.72598411025;18554.90722546392;18595.070246487758;2154.4721543221854;2180.607490651682;2218.456478162844;2227.1425501182;2228.142165266368;119734;146620;147600;149596.25201874028;149796;12245.448954797055;12715.745685086247;12722.809157862639;12723.109382343147;12728.6817829555;16392;16462.929909556082;16473.176341183134;16499.6700065998;16572.254248558846;8719.47683139001;8970.730878073668;8979.551022448852;9057.411268267542;9090.727278181665;6901.413379862667;8408.32733381328;8412.284955778705;8417.621207045697;8549.358798090168;6812.318768123148;8082.878756818653;8084.8383032339025;8146.429749917449;8175.67297308101;2185.3334732906615;2203.8457307988606;2208.558288342338;2215.5679642469563;2235.3964429603916;991.6083147156833;994.5723338964315;998.8662867645274;1015.1827778638267;1027.3579013116803;19077.950712710655;19751.901240493993;21761.020754066107;21772.56454870894;21782.346529604136;853.7375953722684;894.9060348663427;924.6347692661361;1015.2385710716962;1055.4142450939803;10606.310589811637;11288.0969522438;11313.377764223047;11348.602798901999;11353.432328383547;563480;566926;568750;570847.1457642685;572316;106358.93641063491;131945;132490.33754831165;133258.33370833084;137698;1097.0620119797618;1181.3620644851856;1196.4675719304826;1217.1723228204905;1253.6991122130805;21411.78588214098;22649.660255096518;22741.545169096855;23000.884995574914;23023.53952920966;58127.70936145292;58884.411155889604;59003;59825.40174598198;59871.108377955294;81113;81929;82606.58696706478;82720.93197669866;83307;8813.471191728517;8852.64589416442;8910.821783564423;9055.547222638974;9193.954030229806;10444.582216711246;13329.200247985153;14502;14530.709385812013;14551.199684017482;12194.207376520499;12205.389730513616;12243.755124897274;12416.503339866666;12928.676783080122;11644.941775291069;11693.76612467763;11860.051195904134;12035.819462708241;12119;12223.44994475233;12236.265824050586;12412.937935310265;12413;12514.937425312815;9066;9082.18260356571;9119.224865886474;9130.543472826199;9173.678921237724;11745.765084698429;11797.93818556334;12003.1597788154;12107.636770896886;12202.633920982385 17:51:59 INFO - PID 1636 | |3;mootools.html;1296.5656505070801;1377.6555861034742;1441.0777102654304;1507.0957425544675;1514.7652113922338;8469.491830490168;11687.415629218547;11744.53021879125;11813.291202527855;11903.345316007606;11416.885831141686;11478.426078696073;11606.941965290162;11608.6517404478;11637.650870473879;2525.734797846227;2573.369524466508;2573.4209802794408;2573.5110329037425;2583.3025083227517;2450.791682706972;2501.374656335916;2506.3358210074343;2510.460251046025;2511.7864981476596;2095.8952052397394;2501.1746123779108;2541.542522345977;2548.5922252439614;2550.1839411388364;2444.315591634336;2466.1984854922043;2478.392793765539;2488.228649118761;2497.687789026372;2384.1059602649007;2463.802895768334;2472.505498900227;2479.5412848623064;2493.7132229793624;11496.252743571642;11584.94207528957;11590.246633968849;11605;11638.41807909601;6577.835554111138;6806.353396427331;6830.112085428912;6835.453163746888;6837;3940.6847452203756;4087.7547347159266;4130.855420060314;4192.790360481994;4392.780360981938;1269.9809502857465;1270.7013851744832;1299.7010687541824;1303.9413226404833;1319.7822359310703;16034.518964430968;16410.26153823069;16467.505974820655;16551;16744.246508907007;5460.863478413072;5512.531434828004;5648.181013752983;5665;5676.403977582377;10166.593336266615;11143.609973651046;11802.704932376759;11815.172937894265;11828.11289153317;6470.805875823733;6510.9023364649565;6538.771143010067;6555.6066636002;6558.278589355167;5971.910421343683;6206.937930620636;6240.469560087353;6270.655113968684;6274.090256912722;3566.3937130687846;4183.247015537172;4346.717973064104;4421.911561768747;4466.397036400044;1081.491698901515;1256.2274201366276;1278.2905487454366;1293.7865252233464;1297.7664020476404;662.7978466567647;693.5769180799708;780.1496368957863;813.8535063688474;822.0258993093204;6074.665893375906;6120.60216085953;6212.316645169028;6315.778947736807;6450.77422290227;585.4262822433953;601.5788947736514;611.2054329371799;616.0820377637377;617.5584457113185;3561.982190089033;3769.4345848122452;4272.252355837716;4306.7940976526625;4319.589638984291;12623.621291361274;14967.625809354853;15007.624809379853;15178.772318415233;15233.3906643733;12312.692182695504;14496.710065798625;14550.563483095524;15011.32449039774;15185.62035949088;7526.983857179211;8372.497650141011;8438.493690378597;8581.270591999624;8615.784605384915;6482.703459308252;7048.013278141167;7055.400290975119;7064.788056358318;7074.3633073023675;12486.625401237978;12503.749925001632;12528;12616.053795965523;12745.235285882876;13663.248521331423;13701.862981370059;13829.861701382857;13982;14106.647333816736;7982.321502672105;8164.918350816416;8199.71301004462;8264.090949995376;8264.752057438287;10630.627928022792;10685.57257709683;10728.73178170552;10751.19366047534;10794.136469082297;8835.823283534164;9134.271404722092;9172.954135229282;9183.357164998653;9214.124658157732;8927.419717718327;8966.148215919477;8975.416597921376;8990.28077753765;8999.775005624912;6950.443964482931;8025.277725004544;8045.155258697752;8048.919510804817;8061.4760040597175;5241.50205730455;5922.7927022554;5964.303865586408;5983.162357270073;6095.817125486243;5702.7718891243885;5753.194552762533;5762.596618236683;5919.881602367843;5922.111683247375 17:51:59 INFO - PID 1636 | |4;prototype.html;331.4249776637535;366.26015448793436;378.91474418255893;413.03144127022125;414.2729509710459;5306.752913065431;5752.252207213057;5917.822465326041;5966.642001479914;6112.969435152818;6173.475254603364;6192.50459963203;6211.720472578733;6216.906746398797;6239.937600623993;3813.31360355136;4093.9181216375655;4464.330350447426;4480.171168333853;4490.259107247317;3700.965208529007;4485.013297074638;4485.955140448603;4491.730496170224;4523.3441151033085;3846.9807650961707;4400.185965596359;4435.467743870724;4459.4202753641985;4477.641788656916;4154;4356.629686476637;4425.269830477967;4435.246008178601;4491.4161159049445;3772.2266935278203;3788.6400791924725;3794.772313661189;3796.373598356268;3799.639034291738;4754.857354279343;5348.545373643245;5644.3508996465525;5935.851603709899;6039;4303.763293018883;5124.820631277925;5288.677830542364;5306;5345.786168553253;5732.8853422931725;5795.580082879679;5858.912116318259;6604.471642268607;6629.469642428594;4253.829846806155;4309.664004158721;4327.5239723630375;4333.58830911063;4343.73937563744;6403.711832967481;6808.455323574102;6873.725050997954;7017.140457778642;7090.220075791659;4366.170427618742;4371.672124590668;4386.451693538308;4386.495553011381;4434.889127771831;4010.9571511407244;4174.728642638219;4411.29419292918;4446.288593824973;4461.08547747711;5190.766415511311;5213.983273261753;5233.267342572043;5298.81454149103;5307.681539107666;6571.34286571339;6624.56940298879;6635.568688035262;6637.672465506918;6655.767048153291;1337.8729020743015;1458.8687018168416;1502.1512845242403;1504.7893294938717;1536.6081649179387;926.8877347183407;941.2470023980788;961.7162936933621;982.3909176310733;982.9557669904872;6016.849578760479;6304.590201636879;6325.14610527582;6333.553144295902;6337.429631333202;5684.545236381162;6223.541701028159;6383.234011918506;6928.4803639726215;6951.617661028692;5962.25471816023;6130.540209484307;6270.683156537179;6373.681315934185;6388.776392826228;2681.839089654627;3074.6310442746567;3496.5454490916018;3591.838367273479;3617.7829330239742;6144.477719393813;6470.352964703586;6491.026346048131;6547.639879806657;7057.188423331382;6189.412005859436;6265.686715664289;6944.4791640626145;7051.365377116084;7065.929340706527;5984.072468767261;6512.316206798313;6597.326299382623;6860.588364698134;7106.893396599055;6827.419669328163;6861.828454288683;6886.51794374389;6926.341997510229;6935.653217339012;6216.7824126155365;6594.736210551716;6664.134801814839;6732.596044237362;6777.966110169417;5916.378780228186;7040.542364746275;7051.964740176266;7072.398846098139;7125.681748876475;3795.2788970095594;3881.5832221239607;4284.2930916398445;4370.737755734666;4384.605385515319;6055.879662262496;6418.6469744164515;6943.375096241362;6968.512204145661;6986.54587451814;5867.32525759526;6485.708143133476;6702.262751097473;6712.664366781739;6723.294054124247;6645.813137723397;6684.43182329488;6699.899501507579;6716.798496044931;6801.489888258301;6326.715297811702;6549.934500655123;6562.704678289393;6597.340265973365;6607.372299631527;5975.55183361241;6294.685265736786;6367.586106903036;6627.6354800484505;6680.231773345933;5694.943050569607;6240.625562466266;6523.869522609426;6524.445422139172;6544.462051417818 17:51:59 INFO - PID 1636 | |5;yui.html;773.4469854054352;802.7230605441121;837.187927710121;850.4727069217082;875.2691502595335;16715.749263761038;16835.41076062338;17151.056691881942;18055.81944180558;19300.034998250103;17854.91072544639;17978.190981405834;18138.909305453486;18389.632207355877;18397.908010459963;974.8342781727123;994.5226291380142;995.7908839143752;996.7707427291728;999.9500024998757;943.3349488610513;992.5831150916632;1014.1126514299988;1035.414990530348;1041.656253436364;1053.2311412668757;1078.4338222433246;1081.1350919264596;1081.6700906223598;1083.3716444462232;1069.3637285814966;1070.823314153164;1072.5441687282912;1072.5870539842217;1076.042322333124;783.8353945671416;791.4815795653856;796.2833449895082;797.9680812767482;798.8122791144076;16867;17070.743938840926;17185.828141718674;17341.7398739019;17542.947423154525;13710.52013179534;17198.65602687939;17394.565135871577;17492.03793791341;17663.558411039827;11017.559297628086;13216.140950838164;13228.074034817566;13410.798838017437;13418.060735748502;3488.5813702355626;3489.3021395720957;3508.5088087667746;3510.771799833028;3512.4204506256438;131083;133379;134608.32695836556;136568;138682.3065884674;12505.312207828474;13243.404046817916;13246;13471.461141554228;13476.05667603278;24036;25631.74368256331;25639.359016024377;26001.479970400866;26168.607470887953;7779.688812447439;8019.679212831538;8025.678972841138;8046.235607617267;8075;3817.522809648794;3874.554426240962;3877.418387241936;3903.33643280643;3903.6291552302487;4324.67564932625;4394.318880573516;4424.84513042042;4472.306792447176;4474.485434175112;944.1172503709054;972.6255391674256;985.8718366612294;991.0634450444261;993.9055789699967;617.9752809887644;651.3453978751324;670.3095811314354;678.6064082831946;692.5740669488229;10145.753358925072;10500.369977801203;11310.434478276053;11652.76694466106;12048.216865903689;648.4455790299321;651.8240075179675;663.171036204744;669.0900375489331;681.6251061915926;5429.158480435539;5457.672539647634;5598.972005139948;5659.5755318351285;5669.121286200645;86902;110352;110826.03848480513;111471;112004;70809.64595177094;81235.18764812395;82039.769403459;83047.5847620758;84547.5772621133;76956.61521692356;77081.22918770852;77824.83262751064;78162.6091869537;79896.60051699704;9855.162311203341;10299.330543514647;11104.888951110386;12694.428750706056;12709.17390369623;31708.573114209456;39260.84065376432;39696;40025.59974400219;40886.38670420006;41314.79342603268;51871.22193167181;52205.47794522158;52272.738636306574;53417.465825342806;15512.301946412214;15600;15805.52583422499;16056.277467513757;16309.021458712516;16916.661666766842;17043.40348087811;17509.21208545593;17863.46409607714;17963.910180449013;13474.663133421744;15912.840871591136;16360.672786543964;16430.917845410695;16464.34142634329;15582.142982135638;16135.596610084842;16384.75422868682;16496.75254871202;17502.82497175012;13214;15589.688206236038;15978.520644380222;16273.267702953159;16443.58891027734;11494.137939654393;11665.35840528745;11682.94158529202;11771.941140294244;11861.76276474483;13027.869721303045;13709.17744935307;13754.862451375759;13760.87303487337;13772.24252666113 17:51:59 INFO - PID 1636 | __end_tp_report 17:51:59 INFO - PID 1636 | __start_cc_report 17:51:59 INFO - PID 1636 | _x_x_mozilla_cycle_collect,432 17:51:59 INFO - PID 1636 | __end_cc_report 17:51:59 INFO - PID 1636 | __startTimestamp1493427119803__endTimestamp 17:51:59 INFO - PID 1636 | 17:51:59 INFO - PID 1636 | ------- Summary: start ------- 17:51:59 INFO - PID 1636 | Number of tests: 6 17:51:59 INFO - PID 1636 | 17:51:59 INFO - PID 1636 | [#0] dojo.html Cycles:165 Average:6073.97 Median:6382.26 stddev:789.16 (12.4%) stddev-sans-first:753.21 17:51:59 INFO - PID 1636 | Values: 2975.2 2995.6 3582.1 3628.9 3633.3 5796.3 6038.5 6140.0 6183.4 6236.7 6106.2 6119.5 6154.1 6158.7 6204.6 4459.5 4682.1 5265.4 5397.7 5404.9 5166.4 5527.3 5534.2 5575.5 5592.4 5280.8 5437.3 5449.3 5521.3 5526.1 4869.4 5087.6 5346.3 5402.6 5420.7 4433.4 5389.8 5410.3 5434.3 5442.4 5973.6 6245.7 6253.3 6269.7 6295.7 6401.0 6456.7 6564.9 6635.6 6677.5 6713.7 6740.5 6760.5 6798.9 6807.8 5204.2 6238.9 6327.6 6348.1 6348.3 5406.2 6707.2 6814.7 6905.3 6939.1 6425.9 6543.4 6549.6 6560.0 6599.7 6681.5 6684.5 6691.1 6722.9 6746.6 4811.4 6570.7 6598.4 6599.6 6631.1 6377.1 6478.4 6539.1 6594.1 6629.6 5250.3 5263.2 5283.8 5290.8 5310.2 4552.5 4689.2 4693.8 4717.4 4722.8 5042.9 6044.9 6246.7 6265.1 6289.3 6666.1 6709.1 6741.6 6763.0 6780.3 6378.5 6382.8 6384.0 6408.5 6413.7 5700.0 5818.3 5890.9 5893.6 5903.7 6126.2 6580.2 6769.8 6815.0 6973.3 6487.5 6578.5 6645.9 6734.1 6762.9 6852.3 6940.1 6974.9 6976.6 6984.6 6638.4 6652.8 6658.3 6669.0 6690.1 6025.3 6240.3 6523.8 6587.9 6608.8 6500.9 6503.7 6527.3 6586.7 6595.5 6551.6 6558.0 6558.2 6559.7 6572.8 5095.9 6251.5 6332.1 6382.3 6429.5 6406.8 6419.5 6498.0 6528.6 6549.9 6370.9 6372.2 6388.1 6392.6 6400.1 17:51:59 INFO - PID 1636 | 17:51:59 INFO - PID 1636 | [#1] ext.html Cycles:180 Average:44900.44 Median:324616.00 stddev:79062.20 (24.4%) stddev-sans-first:79214.54 17:51:59 INFO - PID 1636 | Values: 761.1 823.0 1067.9 1083.6 1144.1 36675.8 37614.8 37725.0 37748.6 37870.9 37157.5 37542.2 37550.4 37571.6 37671.0 7725.6 7891.1 8042.6 8684.9 8689.0 8582.3 8713.3 8723.5 8735.8 8744.3 8705.1 8733.3 8754.4 8758.6 8765.5 8673.7 8685.1 8716.7 8721.2 8733.9 8421.6 8447.3 8451.6 8485.7 8518.5 37159.8 37361.4 37394.8 37460.0 37515.6 3146.7 3637.8 3672.6 3673.2 3674.4 30348.2 30543.2 30557.7 30619.5 30660.5 3141.7 3175.3 3175.9 3177.6 3189.7 300403.0 301615.0 301829.0 302772.0 304800.0 39868.6 40389.8 47859.5 48109.8 49427.3 50341.7 50431.0 50839.7 50976.0 51020.2 11994.9 14302.4 14334.4 14390.4 14399.9 11456.5 11564.1 11580.4 11596.9 11765.7 13585.9 13604.0 13621.5 13678.4 13773.1 5032.4 5911.1 5948.7 5966.2 5989.6 896.8 979.1 1018.7 1028.0 1080.4 23790.2 24404.1 24440.6 24459.8 24522.1 967.5 1123.7 1135.4 1154.0 1161.7 12038.0 12561.4 12574.7 12586.4 12613.9 322712.0 324236.0 324276.0 324449.0 324783.0 54394.0 54461.0 54753.0 54857.7 54933.7 1484.6 1524.4 1544.8 1552.2 1558.5 15813.9 19888.5 21125.9 21340.0 22746.0 30454.0 36913.6 37763.6 37812.2 37857.1 238901.0 279570.0 284648.0 285889.0 286292.0 33480.7 33880.7 34856.5 34863.5 35031.0 42851.0 43325.1 44709.6 44760.9 44826.0 26619.5 29494.3 29604.0 30408.8 30772.0 26111.9 28097.0 30503.4 31096.0 31560.2 36800.6 36937.0 37023.1 37059.0 37156.0 29636.0 30095.2 31288.0 31578.9 32331.0 21416.5 24295.4 24297.0 24755.4 24910.5 17:51:59 INFO - PID 1636 | 17:51:59 INFO - PID 1636 | [#2] jquery.html Cycles:180 Average:36063.65 Median:22667.33 stddev:95800.37 (422.6%) stddev-sans-first:96031.70 17:51:59 INFO - PID 1636 | Values: 401.9 429.0 434.5 435.9 463.7 15963.4 21865.2 22574.2 22685.0 22767.8 19530.4 22609.0 23616.9 23765.5 23997.9 5955.5 6172.2 6236.2 6236.8 6236.9 5828.2 6096.0 6115.8 6138.7 6151.6 5870.7 6062.8 6095.1 6103.7 6106.8 5955.5 6012.0 6035.4 6039.4 6077.4 4643.7 5088.8 5403.0 5419.5 5453.7 26319.1 26492.3 26532.5 26775.7 26879.3 2177.2 2246.4 2249.3 2251.9 2255.3 17997.8 18224.2 18267.7 18554.9 18595.1 2154.5 2180.6 2218.5 2227.1 2228.1 119734.0 146620.0 147600.0 149596.3 149796.0 12245.4 12715.7 12722.8 12723.1 12728.7 16392.0 16462.9 16473.2 16499.7 16572.3 8719.5 8970.7 8979.6 9057.4 9090.7 6901.4 8408.3 8412.3 8417.6 8549.4 6812.3 8082.9 8084.8 8146.4 8175.7 2185.3 2203.8 2208.6 2215.6 2235.4 991.6 994.6 998.9 1015.2 1027.4 19078.0 19751.9 21761.0 21772.6 21782.3 853.7 894.9 924.6 1015.2 1055.4 10606.3 11288.1 11313.4 11348.6 11353.4 563480.0 566926.0 568750.0 570847.1 572316.0 106358.9 131945.0 132490.3 133258.3 137698.0 1097.1 1181.4 1196.5 1217.2 1253.7 21411.8 22649.7 22741.5 23000.9 23023.5 58127.7 58884.4 59003.0 59825.4 59871.1 81113.0 81929.0 82606.6 82720.9 83307.0 8813.5 8852.6 8910.8 9055.5 9194.0 10444.6 13329.2 14502.0 14530.7 14551.2 12194.2 12205.4 12243.8 12416.5 12928.7 11644.9 11693.8 11860.1 12035.8 12119.0 12223.4 12236.3 12412.9 12413.0 12514.9 9066.0 9082.2 9119.2 9130.5 9173.7 11745.8 11797.9 12003.2 12107.6 12202.6 17:51:59 INFO - PID 1636 | 17:51:59 INFO - PID 1636 | [#3] mootools.html Cycles:180 Average:6960.63 Median:3667.91 stddev:4411.22 (120.3%) stddev-sans-first:4403.06 17:51:59 INFO - PID 1636 | Values: 1296.6 1377.7 1441.1 1507.1 1514.8 8469.5 11687.4 11744.5 11813.3 11903.3 11416.9 11478.4 11606.9 11608.7 11637.7 2525.7 2573.4 2573.4 2573.5 2583.3 2450.8 2501.4 2506.3 2510.5 2511.8 2095.9 2501.2 2541.5 2548.6 2550.2 2444.3 2466.2 2478.4 2488.2 2497.7 2384.1 2463.8 2472.5 2479.5 2493.7 11496.3 11584.9 11590.2 11605.0 11638.4 6577.8 6806.4 6830.1 6835.5 6837.0 3940.7 4087.8 4130.9 4192.8 4392.8 1270.0 1270.7 1299.7 1303.9 1319.8 16034.5 16410.3 16467.5 16551.0 16744.2 5460.9 5512.5 5648.2 5665.0 5676.4 10166.6 11143.6 11802.7 11815.2 11828.1 6470.8 6510.9 6538.8 6555.6 6558.3 5971.9 6206.9 6240.5 6270.7 6274.1 3566.4 4183.2 4346.7 4421.9 4466.4 1081.5 1256.2 1278.3 1293.8 1297.8 662.8 693.6 780.1 813.9 822.0 6074.7 6120.6 6212.3 6315.8 6450.8 585.4 601.6 611.2 616.1 617.6 3562.0 3769.4 4272.3 4306.8 4319.6 12623.6 14967.6 15007.6 15178.8 15233.4 12312.7 14496.7 14550.6 15011.3 15185.6 7527.0 8372.5 8438.5 8581.3 8615.8 6482.7 7048.0 7055.4 7064.8 7074.4 12486.6 12503.7 12528.0 12616.1 12745.2 13663.2 13701.9 13829.9 13982.0 14106.6 7982.3 8164.9 8199.7 8264.1 8264.8 10630.6 10685.6 10728.7 10751.2 10794.1 8835.8 9134.3 9173.0 9183.4 9214.1 8927.4 8966.1 8975.4 8990.3 8999.8 6950.4 8025.3 8045.2 8048.9 8061.5 5241.5 5922.8 5964.3 5983.2 6095.8 5702.8 5753.2 5762.6 5919.9 5922.1 17:51:59 INFO - PID 1636 | 17:51:59 INFO - PID 1636 | [#4] prototype.html Cycles:180 Average:5278.51 Median:6084.42 stddev:1707.30 (28.1%) stddev-sans-first:1671.22 17:51:59 INFO - PID 1636 | Values: 331.4 366.3 378.9 413.0 414.3 5306.8 5752.3 5917.8 5966.6 6113.0 6173.5 6192.5 6211.7 6216.9 6239.9 3813.3 4093.9 4464.3 4480.2 4490.3 3701.0 4485.0 4486.0 4491.7 4523.3 3847.0 4400.2 4435.5 4459.4 4477.6 4154.0 4356.6 4425.3 4435.2 4491.4 3772.2 3788.6 3794.8 3796.4 3799.6 4754.9 5348.5 5644.4 5935.9 6039.0 4303.8 5124.8 5288.7 5306.0 5345.8 5732.9 5795.6 5858.9 6604.5 6629.5 4253.8 4309.7 4327.5 4333.6 4343.7 6403.7 6808.5 6873.7 7017.1 7090.2 4366.2 4371.7 4386.5 4386.5 4434.9 4011.0 4174.7 4411.3 4446.3 4461.1 5190.8 5214.0 5233.3 5298.8 5307.7 6571.3 6624.6 6635.6 6637.7 6655.8 1337.9 1458.9 1502.2 1504.8 1536.6 926.9 941.2 961.7 982.4 983.0 6016.8 6304.6 6325.1 6333.6 6337.4 5684.5 6223.5 6383.2 6928.5 6951.6 5962.3 6130.5 6270.7 6373.7 6388.8 2681.8 3074.6 3496.5 3591.8 3617.8 6144.5 6470.4 6491.0 6547.6 7057.2 6189.4 6265.7 6944.5 7051.4 7065.9 5984.1 6512.3 6597.3 6860.6 7106.9 6827.4 6861.8 6886.5 6926.3 6935.7 6216.8 6594.7 6664.1 6732.6 6778.0 5916.4 7040.5 7052.0 7072.4 7125.7 3795.3 3881.6 4284.3 4370.7 4384.6 6055.9 6418.6 6943.4 6968.5 6986.5 5867.3 6485.7 6702.3 6712.7 6723.3 6645.8 6684.4 6699.9 6716.8 6801.5 6326.7 6549.9 6562.7 6597.3 6607.4 5975.6 6294.7 6367.6 6627.6 6680.2 5694.9 6240.6 6523.9 6524.4 6544.5 17:51:59 INFO - PID 1636 | 17:51:59 INFO - PID 1636 | [#5] yui.html Cycles:180 Average:21427.02 Median:18017.01 stddev:30770.10 (170.8%) stddev-sans-first:30817.33 17:51:59 INFO - PID 1636 | Values: 773.4 802.7 837.2 850.5 875.3 16715.7 16835.4 17151.1 18055.8 19300.0 17854.9 17978.2 18138.9 18389.6 18397.9 974.8 994.5 995.8 996.8 1000.0 943.3 992.6 1014.1 1035.4 1041.7 1053.2 1078.4 1081.1 1081.7 1083.4 1069.4 1070.8 1072.5 1072.6 1076.0 783.8 791.5 796.3 798.0 798.8 16867.0 17070.7 17185.8 17341.7 17542.9 13710.5 17198.7 17394.6 17492.0 17663.6 11017.6 13216.1 13228.1 13410.8 13418.1 3488.6 3489.3 3508.5 3510.8 3512.4 131083.0 133379.0 134608.3 136568.0 138682.3 12505.3 13243.4 13246.0 13471.5 13476.1 24036.0 25631.7 25639.4 26001.5 26168.6 7779.7 8019.7 8025.7 8046.2 8075.0 3817.5 3874.6 3877.4 3903.3 3903.6 4324.7 4394.3 4424.8 4472.3 4474.5 944.1 972.6 985.9 991.1 993.9 618.0 651.3 670.3 678.6 692.6 10145.8 10500.4 11310.4 11652.8 12048.2 648.4 651.8 663.2 669.1 681.6 5429.2 5457.7 5599.0 5659.6 5669.1 86902.0 110352.0 110826.0 111471.0 112004.0 70809.6 81235.2 82039.8 83047.6 84547.6 76956.6 77081.2 77824.8 78162.6 79896.6 9855.2 10299.3 11104.9 12694.4 12709.2 31708.6 39260.8 39696.0 40025.6 40886.4 41314.8 51871.2 52205.5 52272.7 53417.5 15512.3 15600.0 15805.5 16056.3 16309.0 16916.7 17043.4 17509.2 17863.5 17963.9 13474.7 15912.8 16360.7 16430.9 16464.3 15582.1 16135.6 16384.8 16496.8 17502.8 13214.0 15589.7 15978.5 16273.3 16443.6 11494.1 11665.4 11682.9 11771.9 11861.8 13027.9 13709.2 13754.9 13760.9 13772.2 17:51:59 INFO - PID 1636 | -------- Summary: end -------- 17:51:59 INFO - PID 1636 | 17:52:00 INFO - TEST-INFO | 1636: exit 0 17:52:00 INFO - TEST-OK | dromaeo_css | took 1114948ms 17:52:00 INFO - TEST-START | kraken 17:52:00 INFO - operating with platform_type : mac_ 17:52:00 INFO - Initialising browser for kraken test... 17:52:00 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/tmpToYS0S/profile http://localhost:49230/getInfo.html) 17:52:03 INFO - PID 1647 | 2017-04-28 17:52:03.040 plugin-container[1648:10167] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 17:52:03 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:52:03 INFO - PID 1647 | 2017-04-28 17:52:03.043 plugin-container[1648:10167] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4717, name = 'com.apple.CFPasteboardClient' 17:52:03 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:52:03 INFO - PID 1647 | 2017-04-28 17:52:03.043 plugin-container[1648:10167] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:52:03 INFO - PID 1647 | __metrics Screen width/height:1600/1200 17:52:03 INFO - PID 1647 | colorDepth:24 17:52:03 INFO - PID 1647 | Browser inner width/height: 1024/689 17:52:03 INFO - PID 1647 | __metrics 17:52:03 ERROR - PID 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3424: TypeError: this.tabs is undefined 17:52:04 INFO - TEST-INFO | 1647: exit 0 17:52:04 INFO - Browser initialized. 17:52:04 INFO - Running cycle 1/1 for kraken test... 17:52:04 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/tmpToYS0S/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:52:06 INFO - PID 1649 | 2017-04-28 17:52:06.458 plugin-container[1650:10289] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 17:52:06 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:52:06 INFO - PID 1649 | 2017-04-28 17:52:06.459 plugin-container[1650:10289] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9613, name = 'com.apple.CFPasteboardClient' 17:52:06 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:52:06 INFO - PID 1649 | 2017-04-28 17:52:06.459 plugin-container[1650:10289] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:52:16 INFO - PID 1649 | RSS: Main: 251424768 17:52:16 INFO - PID 1649 | 17:52:19 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-beat-detection) 17:52:20 INFO - PID 1649 | RSS: Main: 259616768 17:52:20 INFO - PID 1649 | 17:52:22 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-dft) 17:52:23 INFO - PID 1649 | RSS: Main: 266584064 17:52:23 INFO - PID 1649 | 17:52:26 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-fft) 17:52:27 INFO - PID 1649 | RSS: Main: 262709248 17:52:27 INFO - PID 1649 | 17:52:29 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-oscillator) 17:52:30 INFO - PID 1649 | RSS: Main: 264732672 17:52:30 INFO - PID 1649 | 17:52:32 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:52:32 INFO - PID 1649 | RSS: Main: 265539584 17:52:32 INFO - PID 1649 | 17:52:35 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-darkroom) 17:52:36 INFO - PID 1649 | RSS: Main: 270938112 17:52:36 INFO - PID 1649 | 17:52:39 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-desaturate) 17:52:40 INFO - PID 1649 | RSS: Main: 272711680 17:52:40 INFO - PID 1649 | 17:52:42 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49230/tests/kraken/driver.html?testName=json-parse-financial) 17:52:43 INFO - PID 1649 | RSS: Main: 271654912 17:52:43 INFO - PID 1649 | 17:52:45 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49230/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:52:45 INFO - PID 1649 | RSS: Main: 271507456 17:52:45 INFO - PID 1649 | 17:52:47 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:52:48 INFO - PID 1649 | RSS: Main: 271781888 17:52:48 INFO - PID 1649 | 17:52:50 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:52:50 INFO - PID 1649 | RSS: Main: 271196160 17:52:50 INFO - PID 1649 | 17:52:53 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:52:54 INFO - PID 1649 | RSS: Main: 271204352 17:52:54 INFO - PID 1649 | 17:52:57 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:52:58 INFO - PID 1649 | RSS: Main: 271474688 17:52:58 INFO - PID 1649 | 17:52:59 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49230/tests/kraken/driver.html?testName=ai-astar) 17:53:00 INFO - PID 1649 | RSS: Main: 273444864 17:53:00 INFO - PID 1649 | 17:53:00 INFO - PID 1649 | __start_tp_report 17:53:00 INFO - PID 1649 | _x_x_mozilla_page_load 17:53:00 INFO - PID 1649 | _x_x_mozilla_page_load_details 17:53:00 INFO - PID 1649 | |i|pagename|runs| 17:53:00 INFO - PID 1649 | |0;ai-astar;103;112;113;113;115;112;112;114;117;112 17:53:00 INFO - PID 1649 | |1;audio-beat-detection;136;125;141;193;119;111;109;108;139;111 17:53:00 INFO - PID 1649 | |2;audio-dft;275;251;201;219;211;244;201;209;196;196 17:53:00 INFO - PID 1649 | |3;audio-fft;85;64;73;113;90;69;64;60;76;55 17:53:00 INFO - PID 1649 | |4;audio-oscillator;87;66;83;72;69;69;86;72;91;81 17:53:00 INFO - PID 1649 | |5;imaging-gaussian-blur;88;90;94;88;89;88;89;96;137;139 17:53:00 INFO - PID 1649 | |6;imaging-darkroom;103;95;95;96;96;96;97;138;97;97 17:53:00 INFO - PID 1649 | |7;imaging-desaturate;91;94;91;91;93;89;91;92;91;90 17:53:00 INFO - PID 1649 | |8;json-parse-financial;51;51;50;49;51;69;44;50;56;65 17:53:00 INFO - PID 1649 | |9;json-stringify-tinderbox;58;64;56;59;60;62;59;60;57;59 17:53:00 INFO - PID 1649 | |10;stanford-crypto-aes;84;85;81;84;82;83;81;83;83;85 17:53:00 INFO - PID 1649 | |11;stanford-crypto-ccm;140;146;140;136;140;136;139;144;188;142 17:53:00 INFO - PID 1649 | |12;stanford-crypto-pbkdf2;204;189;170;189;185;185;197;223;187;189 17:53:00 INFO - PID 1649 | |13;stanford-crypto-sha256-iterative;70;71;69;70;67;71;66;69;70;69 17:53:00 INFO - PID 1649 | __end_tp_report 17:53:00 INFO - PID 1649 | __start_cc_report 17:53:00 INFO - PID 1649 | _x_x_mozilla_cycle_collect,1011 17:53:00 INFO - PID 1649 | __end_cc_report 17:53:00 INFO - PID 1649 | __startTimestamp1493427180064__endTimestamp 17:53:00 INFO - PID 1649 | 17:53:00 INFO - PID 1649 | ------- Summary: start ------- 17:53:00 INFO - PID 1649 | Number of tests: 14 17:53:00 INFO - PID 1649 | 17:53:00 INFO - PID 1649 | [#0] ai-astar Cycles:10 Average:112.30 Median:113.00 stddev:3.65 (3.2%) stddev-sans-first:1.73 17:53:00 INFO - PID 1649 | Values: 103.0 112.0 113.0 113.0 115.0 112.0 112.0 114.0 117.0 112.0 17:53:00 INFO - PID 1649 | 17:53:00 INFO - PID 1649 | [#1] audio-beat-detection Cycles:10 Average:129.20 Median:130.50 stddev:25.81 (19.8%) stddev-sans-first:27.25 17:53:00 INFO - PID 1649 | Values: 136.0 125.0 141.0 193.0 119.0 111.0 109.0 108.0 139.0 111.0 17:53:00 INFO - PID 1649 | 17:53:00 INFO - PID 1649 | [#2] audio-dft Cycles:10 Average:220.30 Median:215.00 stddev:27.16 (12.6%) stddev-sans-first:20.35 17:53:00 INFO - PID 1649 | Values: 275.0 251.0 201.0 219.0 211.0 244.0 201.0 209.0 196.0 196.0 17:53:00 INFO - PID 1649 | 17:53:00 INFO - PID 1649 | [#3] audio-fft Cycles:10 Average:74.90 Median:71.00 stddev:17.25 (24.3%) stddev-sans-first:17.90 17:53:00 INFO - PID 1649 | Values: 85.0 64.0 73.0 113.0 90.0 69.0 64.0 60.0 76.0 55.0 17:53:00 INFO - PID 1649 | 17:53:00 INFO - PID 1649 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:82.00 stddev:8.97 (10.9%) stddev-sans-first:8.85 17:53:00 INFO - PID 1649 | Values: 87.0 66.0 83.0 72.0 69.0 69.0 86.0 72.0 91.0 81.0 17:53:00 INFO - PID 1649 | 17:53:00 INFO - PID 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:99.80 Median:89.00 stddev:20.32 (22.8%) stddev-sans-first:21.10 17:53:00 INFO - PID 1649 | Values: 88.0 90.0 94.0 88.0 89.0 88.0 89.0 96.0 137.0 139.0 17:53:00 INFO - PID 1649 | 17:53:00 INFO - PID 1649 | [#6] imaging-darkroom Cycles:10 Average:101.00 Median:96.00 stddev:13.20 (13.7%) stddev-sans-first:13.98 17:53:00 INFO - PID 1649 | Values: 103.0 95.0 95.0 96.0 96.0 96.0 97.0 138.0 97.0 97.0 17:53:00 INFO - PID 1649 | 17:53:00 INFO - PID 1649 | [#7] imaging-desaturate Cycles:10 Average:91.30 Median:91.00 stddev:1.42 (1.6%) stddev-sans-first:1.50 17:53:00 INFO - PID 1649 | Values: 91.0 94.0 91.0 91.0 93.0 89.0 91.0 92.0 91.0 90.0 17:53:00 INFO - PID 1649 | 17:53:00 INFO - PID 1649 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:51.00 stddev:7.69 (15.1%) stddev-sans-first:8.10 17:53:00 INFO - PID 1649 | Values: 51.0 51.0 50.0 49.0 51.0 69.0 44.0 50.0 56.0 65.0 17:53:00 INFO - PID 1649 | 17:53:00 INFO - PID 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:59.40 Median:59.50 stddev:2.32 (3.9%) stddev-sans-first:2.40 17:53:00 INFO - PID 1649 | Values: 58.0 64.0 56.0 59.0 60.0 62.0 59.0 60.0 57.0 59.0 17:53:00 INFO - PID 1649 | 17:53:00 INFO - PID 1649 | [#10] stanford-crypto-aes Cycles:10 Average:83.10 Median:83.50 stddev:1.45 (1.7%) stddev-sans-first:1.50 17:53:00 INFO - PID 1649 | Values: 84.0 85.0 81.0 84.0 82.0 83.0 81.0 83.0 83.0 85.0 17:53:00 INFO - PID 1649 | 17:53:00 INFO - PID 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:145.10 Median:141.00 stddev:15.39 (10.9%) stddev-sans-first:16.22 17:53:00 INFO - PID 1649 | Values: 140.0 146.0 140.0 136.0 140.0 136.0 139.0 144.0 188.0 142.0 17:53:00 INFO - PID 1649 | 17:53:00 INFO - PID 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.80 Median:189.00 stddev:14.00 (7.4%) stddev-sans-first:14.13 17:53:00 INFO - PID 1649 | Values: 204.0 189.0 170.0 189.0 185.0 185.0 197.0 223.0 187.0 189.0 17:53:00 INFO - PID 1649 | 17:53:00 INFO - PID 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:70.00 stddev:1.62 (2.3%) stddev-sans-first:1.69 17:53:00 INFO - PID 1649 | Values: 70.0 71.0 69.0 70.0 67.0 71.0 66.0 69.0 70.0 69.0 17:53:00 INFO - PID 1649 | -------- Summary: end -------- 17:53:00 INFO - PID 1649 | 17:53:01 INFO - TEST-INFO | 1649: exit 0 17:53:01 INFO - TEST-OK | kraken | took 60692ms 17:53:01 INFO - SUITE-END | took 1175s 17:53:01 INFO - Completed test suite (00:19:35) 17:53:01 INFO - javascript benchmark 17:53:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7815.592054195781, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2975.2115689342327, 2995.610570625818, 3582.1402863312815, 3628.927421451571, 3633.309671162479, 5796.275465566804, 6038.5471089668345, 6140.048292514662, 6183.350748171439, 6236.656983865885, 6106.187656479749, 6119.502553941423, 6154.138420621117, 6158.6612736299485, 6204.627722336663, 4459.464864216298, 4682.08699303636, 5265.447128051547, 5397.676139431627, 5404.891902161954, 5166.354205724285, 5527.2538207342095, 5534.169874518814, 5575.470330318613, 5592.356878958931, 5280.815171469018, 5437.32033495813, 5449.3460784706085, 5521.337439507265, 5526.06056970314, 4869.366982292315, 5087.643864929457, 5346.278252435949, 5402.621816472849, 5420.7018613976215, 4433.379326894237, 5389.784408623689, 5410.296661433988, 5434.2935418395755, 5442.428545002757, 5973.581849270553, 6245.687715614201, 6253.343398943137, 6269.655168965705, 6295.653739044351, 6401.0, 6456.6771661417215, 6564.868702625969, 6635.635040072794, 6677.499187560904, 6713.664316784141, 6740.460763138986, 6760.459163266976, 6798.864022719518, 6807.829804254884, 5204.219367094928, 6238.875222495615, 6327.588706734047, 6348.143000694893, 6348.333424990402, 5406.243125962368, 6707.228668703064, 6814.727410903607, 6905.309469053155, 6939.132608423947, 6425.871482570323, 6543.411093001652, 6549.574277671936, 6560.048792924999, 6599.70301336441, 6681.465482761368, 6684.465242780566, 6691.130153080166, 6722.932770672329, 6746.628935408599, 4811.398575178103, 6570.737170513222, 6598.37315455031, 6599.60402375859, 6631.137952066297, 6377.139086223393, 6478.352164783484, 6539.051837483538, 6594.07682924391, 6629.602223866584, 5250.343707036621, 5263.210518422191, 5283.841484755463, 5290.841274761763, 5310.150375939831, 4552.499225085238, 4689.226277664147, 4693.78877950493, 4717.363155973968, 4722.834700785456, 5042.924356134661, 6044.911915855188, 6246.6876656167005, 6265.122882796412, 6289.339619339995, 6666.0667506549125, 6709.094272273278, 6741.59550426976, 6763.0, 6780.288069752605, 6378.521610879203, 6382.840428989312, 6384.042393640898, 6408.455281301049, 6413.743450262031, 5700.0, 5818.272715910512, 5890.8821823563285, 5893.616914900518, 5903.734331955073, 6126.23422072241, 6580.243272023778, 6769.796906092924, 6815.0, 6973.302669732986, 6487.54587178893, 6578.5395022347975, 6645.9003114954285, 6734.090897728744, 6762.8647427052165, 6852.349026842442, 6940.09778728762, 6974.930250697428, 6976.616286104313, 6984.615846128511, 6638.369354911276, 6652.767153149616, 6658.267590565131, 6669.0, 6690.096836927147, 6025.276966764016, 6240.251169859464, 6523.836904077436, 6587.934120658732, 6608.801735947929, 6500.869982600227, 6503.674816259263, 6527.314631963765, 6586.70359833799, 6595.472362210916, 6551.574147680385, 6558.04908288286, 6558.1802274715665, 6559.704813283318, 6572.76995305162, 5095.949040509548, 6251.499880009679, 6332.0501924709815, 6382.255969627187, 6429.51778616615, 6406.839829004312, 6419.454346380423, 6498.0, 6528.608283503005, 6549.901751473636, 6370.904436433365, 6372.203474565679, 6388.105665206967, 6392.552521323462, 6400.103985441949], "value": 6019.36355616462, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [761.0866959648422, 823.0452674897118, 1067.8558394616725, 1083.5611577311188, 1144.107596075061, 36675.81662091689, 37614.8119259404, 37725.0, 37748.622513774855, 37870.863874083785, 37157.51369945199, 37542.249155016885, 37550.4367434488, 37571.624283757155, 37671.0, 7725.61371931404, 7891.0925243597, 8042.557659328734, 8684.8697269541, 8689.0, 8582.270507006911, 8713.259372953275, 8723.520206388646, 8735.781605459852, 8744.300455963508, 8705.129487051308, 8733.34499912506, 8754.430961987482, 8758.56207189638, 8765.474071555695, 8673.739787806344, 8685.131486851264, 8716.694915677921, 8721.171488708562, 8733.912660873373, 8421.578921053922, 8447.32421406286, 8451.619677114544, 8485.745427637181, 8518.488890666518, 37159.81420092909, 37361.439578406345, 37394.813025934694, 37460.0, 37515.62484375176, 3146.6695996920453, 3637.781733096023, 3672.6143754905884, 3673.210259794157, 3674.375356189454, 30348.241293967607, 30543.236419089477, 30557.694423055706, 30619.540706889413, 30660.540091898638, 3141.7486601072087, 3175.3490534440384, 3175.9364812703616, 3177.5869137012273, 3189.6650851660706, 300403.0, 301615.0, 301829.0, 302772.0, 304800.0, 39868.60131398649, 40389.79805100956, 47859.521404785504, 48109.75945120322, 49427.258591121164, 50341.74829125831, 50431.0, 50839.74580127076, 50976.0, 51020.23469647958, 11994.940025299818, 14302.427902883976, 14334.354954027094, 14390.352434140488, 14399.856001440061, 11456.54173833054, 11564.074874010059, 11580.36308003068, 11596.94201528987, 11765.705857353463, 13585.932070339584, 13604.0, 13621.455141794217, 13678.384472698752, 13773.14062601556, 5032.446430892599, 5911.142884281755, 5948.67282299469, 5966.224390829251, 5989.55078369124, 896.7623579751371, 979.0747743382435, 1018.6689325969089, 1027.984580231297, 1080.36258607422, 23790.167344142872, 24404.14585489535, 24440.633390499155, 24459.755402446102, 24522.14172503954, 967.5307475874196, 1123.741539445932, 1135.3868910788246, 1154.0363796230074, 1161.651504548649, 12038.0, 12561.434735436927, 12574.685632859251, 12586.37068146589, 12613.936930315289, 322712.0, 324236.0, 324276.0, 324449.0, 324783.0, 54394.0, 54461.0, 54753.0, 54857.72571137119, 54933.725331373884, 1484.5694748523024, 1524.3521503361028, 1544.8300686924429, 1552.1773460066183, 1558.4701201591286, 15813.920930395274, 19888.50278743043, 21125.894370528666, 21340.03969821393, 22746.0, 30454.0, 36913.63086369102, 37763.62236377711, 37812.2437551242, 37857.05357366088, 238901.0, 279570.0, 284648.0, 285889.0, 286292.0, 33480.66077356976, 33880.66119338874, 34856.47715284222, 34863.47704784379, 35031.0, 42851.0, 43325.133497330506, 44709.55290447184, 44760.88097797581, 44826.0, 26619.467610648066, 29494.262643434085, 29603.963861264754, 30408.84795576008, 30772.0, 26111.869440652674, 28097.0, 30503.38993220079, 31096.0, 31560.210994725316, 36800.63199368079, 36937.0, 37023.07442313856, 37059.0, 37156.0, 29635.962741303952, 30095.247618809706, 31288.0, 31578.894738684037, 32331.030069097025, 21416.464588385414, 24295.392615184763, 24297.0, 24755.381115472257, 24910.501789964463], "value": 17418.677010700725, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [401.92765302245596, 428.9785510724463, 434.4916447756127, 435.87141793171, 463.6661603645374, 15963.361465541364, 21865.234716784915, 22574.20990265337, 22685.0, 22767.77232227677, 19530.41408757736, 22609.0, 23616.881915590442, 23765.5246895062, 23997.88001059992, 5955.464008239247, 6172.197614310156, 6236.158118653969, 6236.781712640059, 6236.906446403307, 5828.184054232411, 6096.0, 6115.847103822418, 6138.7237574309265, 6151.569390142692, 5870.677112758796, 6062.757489700429, 6095.05526643371, 6103.725332360033, 6106.8473288167925, 5955.493783028448, 6012.0, 6035.396460353952, 6039.4262545058145, 6077.361877002917, 4643.674942754009, 5088.821891233789, 5403.0, 5419.485148910847, 5453.727313634303, 26319.052142178636, 26492.337691557674, 26532.46935061288, 26775.732242677517, 26879.32801679954, 2177.1617927098023, 2246.3597874605657, 2249.280230326296, 2251.8648881067024, 2255.289583781104, 17997.820021799744, 18224.179911903997, 18267.72598411025, 18554.90722546392, 18595.070246487758, 2154.4721543221854, 2180.607490651682, 2218.456478162844, 2227.1425501182, 2228.142165266368, 119734.0, 146620.0, 147600.0, 149596.25201874028, 149796.0, 12245.448954797055, 12715.745685086247, 12722.809157862639, 12723.109382343147, 12728.6817829555, 16392.0, 16462.929909556082, 16473.176341183134, 16499.6700065998, 16572.254248558846, 8719.47683139001, 8970.730878073668, 8979.551022448852, 9057.411268267542, 9090.727278181665, 6901.413379862667, 8408.32733381328, 8412.284955778705, 8417.621207045697, 8549.358798090168, 6812.318768123148, 8082.878756818653, 8084.8383032339025, 8146.429749917449, 8175.67297308101, 2185.3334732906615, 2203.8457307988606, 2208.558288342338, 2215.5679642469563, 2235.3964429603916, 991.6083147156833, 994.5723338964315, 998.8662867645274, 1015.1827778638267, 1027.3579013116803, 19077.950712710655, 19751.901240493993, 21761.020754066107, 21772.56454870894, 21782.346529604136, 853.7375953722684, 894.9060348663427, 924.6347692661361, 1015.2385710716962, 1055.4142450939803, 10606.310589811637, 11288.0969522438, 11313.377764223047, 11348.602798901999, 11353.432328383547, 563480.0, 566926.0, 568750.0, 570847.1457642685, 572316.0, 106358.93641063491, 131945.0, 132490.33754831165, 133258.33370833084, 137698.0, 1097.0620119797618, 1181.3620644851856, 1196.4675719304826, 1217.1723228204905, 1253.6991122130805, 21411.78588214098, 22649.660255096518, 22741.545169096855, 23000.884995574914, 23023.53952920966, 58127.70936145292, 58884.411155889604, 59003.0, 59825.40174598198, 59871.108377955294, 81113.0, 81929.0, 82606.58696706478, 82720.93197669866, 83307.0, 8813.471191728517, 8852.64589416442, 8910.821783564423, 9055.547222638974, 9193.954030229806, 10444.582216711246, 13329.200247985153, 14502.0, 14530.709385812013, 14551.199684017482, 12194.207376520499, 12205.389730513616, 12243.755124897274, 12416.503339866666, 12928.676783080122, 11644.941775291069, 11693.76612467763, 11860.051195904134, 12035.819462708241, 12119.0, 12223.44994475233, 12236.265824050586, 12412.937935310265, 12413.0, 12514.937425312815, 9066.0, 9082.18260356571, 9119.224865886474, 9130.543472826199, 9173.678921237724, 11745.765084698429, 11797.93818556334, 12003.1597788154, 12107.636770896886, 12202.633920982385], "value": 10547.019386294374, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1296.5656505070801, 1377.6555861034742, 1441.0777102654304, 1507.0957425544675, 1514.7652113922338, 8469.491830490168, 11687.415629218547, 11744.53021879125, 11813.291202527855, 11903.345316007606, 11416.885831141686, 11478.426078696073, 11606.941965290162, 11608.6517404478, 11637.650870473879, 2525.734797846227, 2573.369524466508, 2573.4209802794408, 2573.5110329037425, 2583.3025083227517, 2450.791682706972, 2501.374656335916, 2506.3358210074343, 2510.460251046025, 2511.7864981476596, 2095.8952052397394, 2501.1746123779108, 2541.542522345977, 2548.5922252439614, 2550.1839411388364, 2444.315591634336, 2466.1984854922043, 2478.392793765539, 2488.228649118761, 2497.687789026372, 2384.1059602649007, 2463.802895768334, 2472.505498900227, 2479.5412848623064, 2493.7132229793624, 11496.252743571642, 11584.94207528957, 11590.246633968849, 11605.0, 11638.41807909601, 6577.835554111138, 6806.353396427331, 6830.112085428912, 6835.453163746888, 6837.0, 3940.6847452203756, 4087.7547347159266, 4130.855420060314, 4192.790360481994, 4392.780360981938, 1269.9809502857465, 1270.7013851744832, 1299.7010687541824, 1303.9413226404833, 1319.7822359310703, 16034.518964430968, 16410.26153823069, 16467.505974820655, 16551.0, 16744.246508907007, 5460.863478413072, 5512.531434828004, 5648.181013752983, 5665.0, 5676.403977582377, 10166.593336266615, 11143.609973651046, 11802.704932376759, 11815.172937894265, 11828.11289153317, 6470.805875823733, 6510.9023364649565, 6538.771143010067, 6555.6066636002, 6558.278589355167, 5971.910421343683, 6206.937930620636, 6240.469560087353, 6270.655113968684, 6274.090256912722, 3566.3937130687846, 4183.247015537172, 4346.717973064104, 4421.911561768747, 4466.397036400044, 1081.491698901515, 1256.2274201366276, 1278.2905487454366, 1293.7865252233464, 1297.7664020476404, 662.7978466567647, 693.5769180799708, 780.1496368957863, 813.8535063688474, 822.0258993093204, 6074.665893375906, 6120.60216085953, 6212.316645169028, 6315.778947736807, 6450.77422290227, 585.4262822433953, 601.5788947736514, 611.2054329371799, 616.0820377637377, 617.5584457113185, 3561.982190089033, 3769.4345848122452, 4272.252355837716, 4306.7940976526625, 4319.589638984291, 12623.621291361274, 14967.625809354853, 15007.624809379853, 15178.772318415233, 15233.3906643733, 12312.692182695504, 14496.710065798625, 14550.563483095524, 15011.32449039774, 15185.62035949088, 7526.983857179211, 8372.497650141011, 8438.493690378597, 8581.270591999624, 8615.784605384915, 6482.703459308252, 7048.013278141167, 7055.400290975119, 7064.788056358318, 7074.3633073023675, 12486.625401237978, 12503.749925001632, 12528.0, 12616.053795965523, 12745.235285882876, 13663.248521331423, 13701.862981370059, 13829.861701382857, 13982.0, 14106.647333816736, 7982.321502672105, 8164.918350816416, 8199.71301004462, 8264.090949995376, 8264.752057438287, 10630.627928022792, 10685.57257709683, 10728.73178170552, 10751.19366047534, 10794.136469082297, 8835.823283534164, 9134.271404722092, 9172.954135229282, 9183.357164998653, 9214.124658157732, 8927.419717718327, 8966.148215919477, 8975.416597921376, 8990.28077753765, 8999.775005624912, 6950.443964482931, 8025.277725004544, 8045.155258697752, 8048.919510804817, 8061.4760040597175, 5241.50205730455, 5922.7927022554, 5964.303865586408, 5983.162357270073, 6095.817125486243, 5702.7718891243885, 5753.194552762533, 5762.596618236683, 5919.881602367843, 5922.111683247375], "value": 5232.972891061775, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.4249776637535, 366.26015448793436, 378.91474418255893, 413.03144127022125, 414.2729509710459, 5306.752913065431, 5752.252207213057, 5917.822465326041, 5966.642001479914, 6112.969435152818, 6173.475254603364, 6192.50459963203, 6211.720472578733, 6216.906746398797, 6239.937600623993, 3813.31360355136, 4093.9181216375655, 4464.330350447426, 4480.171168333853, 4490.259107247317, 3700.965208529007, 4485.013297074638, 4485.955140448603, 4491.730496170224, 4523.3441151033085, 3846.9807650961707, 4400.185965596359, 4435.467743870724, 4459.4202753641985, 4477.641788656916, 4154.0, 4356.629686476637, 4425.269830477967, 4435.246008178601, 4491.4161159049445, 3772.2266935278203, 3788.6400791924725, 3794.772313661189, 3796.373598356268, 3799.639034291738, 4754.857354279343, 5348.545373643245, 5644.3508996465525, 5935.851603709899, 6039.0, 4303.763293018883, 5124.820631277925, 5288.677830542364, 5306.0, 5345.786168553253, 5732.8853422931725, 5795.580082879679, 5858.912116318259, 6604.471642268607, 6629.469642428594, 4253.829846806155, 4309.664004158721, 4327.5239723630375, 4333.58830911063, 4343.73937563744, 6403.711832967481, 6808.455323574102, 6873.725050997954, 7017.140457778642, 7090.220075791659, 4366.170427618742, 4371.672124590668, 4386.451693538308, 4386.495553011381, 4434.889127771831, 4010.9571511407244, 4174.728642638219, 4411.29419292918, 4446.288593824973, 4461.08547747711, 5190.766415511311, 5213.983273261753, 5233.267342572043, 5298.81454149103, 5307.681539107666, 6571.34286571339, 6624.56940298879, 6635.568688035262, 6637.672465506918, 6655.767048153291, 1337.8729020743015, 1458.8687018168416, 1502.1512845242403, 1504.7893294938717, 1536.6081649179387, 926.8877347183407, 941.2470023980788, 961.7162936933621, 982.3909176310733, 982.9557669904872, 6016.849578760479, 6304.590201636879, 6325.14610527582, 6333.553144295902, 6337.429631333202, 5684.545236381162, 6223.541701028159, 6383.234011918506, 6928.4803639726215, 6951.617661028692, 5962.25471816023, 6130.540209484307, 6270.683156537179, 6373.681315934185, 6388.776392826228, 2681.839089654627, 3074.6310442746567, 3496.5454490916018, 3591.838367273479, 3617.7829330239742, 6144.477719393813, 6470.352964703586, 6491.026346048131, 6547.639879806657, 7057.188423331382, 6189.412005859436, 6265.686715664289, 6944.4791640626145, 7051.365377116084, 7065.929340706527, 5984.072468767261, 6512.316206798313, 6597.326299382623, 6860.588364698134, 7106.893396599055, 6827.419669328163, 6861.828454288683, 6886.51794374389, 6926.341997510229, 6935.653217339012, 6216.7824126155365, 6594.736210551716, 6664.134801814839, 6732.596044237362, 6777.966110169417, 5916.378780228186, 7040.542364746275, 7051.964740176266, 7072.398846098139, 7125.681748876475, 3795.2788970095594, 3881.5832221239607, 4284.2930916398445, 4370.737755734666, 4384.605385515319, 6055.879662262496, 6418.6469744164515, 6943.375096241362, 6968.512204145661, 6986.54587451814, 5867.32525759526, 6485.708143133476, 6702.262751097473, 6712.664366781739, 6723.294054124247, 6645.813137723397, 6684.43182329488, 6699.899501507579, 6716.798496044931, 6801.489888258301, 6326.715297811702, 6549.934500655123, 6562.704678289393, 6597.340265973365, 6607.372299631527, 5975.55183361241, 6294.685265736786, 6367.586106903036, 6627.6354800484505, 6680.231773345933, 5694.943050569607, 6240.625562466266, 6523.869522609426, 6524.445422139172, 6544.462051417818], "value": 4733.59497915899, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [773.4469854054352, 802.7230605441121, 837.187927710121, 850.4727069217082, 875.2691502595335, 16715.749263761038, 16835.41076062338, 17151.056691881942, 18055.81944180558, 19300.034998250103, 17854.91072544639, 17978.190981405834, 18138.909305453486, 18389.632207355877, 18397.908010459963, 974.8342781727123, 994.5226291380142, 995.7908839143752, 996.7707427291728, 999.9500024998757, 943.3349488610513, 992.5831150916632, 1014.1126514299988, 1035.414990530348, 1041.656253436364, 1053.2311412668757, 1078.4338222433246, 1081.1350919264596, 1081.6700906223598, 1083.3716444462232, 1069.3637285814966, 1070.823314153164, 1072.5441687282912, 1072.5870539842217, 1076.042322333124, 783.8353945671416, 791.4815795653856, 796.2833449895082, 797.9680812767482, 798.8122791144076, 16867.0, 17070.743938840926, 17185.828141718674, 17341.7398739019, 17542.947423154525, 13710.52013179534, 17198.65602687939, 17394.565135871577, 17492.03793791341, 17663.558411039827, 11017.559297628086, 13216.140950838164, 13228.074034817566, 13410.798838017437, 13418.060735748502, 3488.5813702355626, 3489.3021395720957, 3508.5088087667746, 3510.771799833028, 3512.4204506256438, 131083.0, 133379.0, 134608.32695836556, 136568.0, 138682.3065884674, 12505.312207828474, 13243.404046817916, 13246.0, 13471.461141554228, 13476.05667603278, 24036.0, 25631.74368256331, 25639.359016024377, 26001.479970400866, 26168.607470887953, 7779.688812447439, 8019.679212831538, 8025.678972841138, 8046.235607617267, 8075.0, 3817.522809648794, 3874.554426240962, 3877.418387241936, 3903.33643280643, 3903.6291552302487, 4324.67564932625, 4394.318880573516, 4424.84513042042, 4472.306792447176, 4474.485434175112, 944.1172503709054, 972.6255391674256, 985.8718366612294, 991.0634450444261, 993.9055789699967, 617.9752809887644, 651.3453978751324, 670.3095811314354, 678.6064082831946, 692.5740669488229, 10145.753358925072, 10500.369977801203, 11310.434478276053, 11652.76694466106, 12048.216865903689, 648.4455790299321, 651.8240075179675, 663.171036204744, 669.0900375489331, 681.6251061915926, 5429.158480435539, 5457.672539647634, 5598.972005139948, 5659.5755318351285, 5669.121286200645, 86902.0, 110352.0, 110826.03848480513, 111471.0, 112004.0, 70809.64595177094, 81235.18764812395, 82039.769403459, 83047.5847620758, 84547.5772621133, 76956.61521692356, 77081.22918770852, 77824.83262751064, 78162.6091869537, 79896.60051699704, 9855.162311203341, 10299.330543514647, 11104.888951110386, 12694.428750706056, 12709.17390369623, 31708.573114209456, 39260.84065376432, 39696.0, 40025.59974400219, 40886.38670420006, 41314.79342603268, 51871.22193167181, 52205.47794522158, 52272.738636306574, 53417.465825342806, 15512.301946412214, 15600.0, 15805.52583422499, 16056.277467513757, 16309.021458712516, 16916.661666766842, 17043.40348087811, 17509.21208545593, 17863.46409607714, 17963.910180449013, 13474.663133421744, 15912.840871591136, 16360.672786543964, 16430.917845410695, 16464.34142634329, 15582.142982135638, 16135.596610084842, 16384.75422868682, 16496.75254871202, 17502.82497175012, 13214.0, 15589.688206236038, 15978.520644380222, 16273.267702953159, 16443.58891027734, 11494.137939654393, 11665.35840528745, 11682.94158529202, 11771.941140294244, 11861.76276474483, 13027.869721303045, 13709.17744935307, 13754.862451375759, 13760.87303487337, 13772.24252666113], "value": 8319.63829444466, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1508.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 113.0, 113.0, 115.0, 112.0, 112.0, 114.0, 117.0, 112.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 125.0, 141.0, 193.0, 119.0, 111.0, 109.0, 108.0, 139.0, 111.0], "value": 129.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [275.0, 251.0, 201.0, 219.0, 211.0, 244.0, 201.0, 209.0, 196.0, 196.0], "value": 220.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 64.0, 73.0, 113.0, 90.0, 69.0, 64.0, 60.0, 76.0, 55.0], "value": 74.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 66.0, 83.0, 72.0, 69.0, 69.0, 86.0, 72.0, 91.0, 81.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 94.0, 88.0, 89.0, 88.0, 89.0, 96.0, 137.0, 139.0], "value": 99.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 95.0, 95.0, 96.0, 96.0, 96.0, 97.0, 138.0, 97.0, 97.0], "value": 101.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 91.0, 91.0, 93.0, 89.0, 91.0, 92.0, 91.0, 90.0], "value": 91.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 50.0, 49.0, 51.0, 69.0, 44.0, 50.0, 56.0, 65.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 64.0, 56.0, 59.0, 60.0, 62.0, 59.0, 60.0, 57.0, 59.0], "value": 59.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 81.0, 84.0, 82.0, 83.0, 81.0, 83.0, 83.0, 85.0], "value": 83.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 146.0, 140.0, 136.0, 140.0, 136.0, 139.0, 144.0, 188.0, 142.0], "value": 145.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 189.0, 170.0, 189.0, 185.0, 185.0, 197.0, 223.0, 187.0, 189.0], "value": 191.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 69.0, 70.0, 67.0, 71.0, 66.0, 69.0, 70.0, 69.0], "value": 69.2, "unit": "score"}]}]} 17:53:01 INFO - Return code: 0 17:53:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:53:01 ERROR - # TBPL SUCCESS # 17:53:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:53:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:53:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:53:01 INFO - 2017-04-28 17:53:01.426 screenresolution[1654:10672] starting screenresolution argv=screenresolution get 17:53:01 INFO - 2017-04-28 17:53:01.429 screenresolution[1654:10672] Display 0: 1600x1200x32@60 17:53:01 INFO - 2017-04-28 17:53:01.435 screenresolution[1655:10675] starting screenresolution argv=screenresolution list 17:53:01 INFO - Available Modes on Display 0 17:53:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:53:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:53:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:53:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:53:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:53:01 INFO - Intel Iris: 17:53:01 INFO - Chipset Model: Intel Iris 17:53:01 INFO - Type: GPU 17:53:01 INFO - Bus: Built-In 17:53:01 INFO - VRAM (Dynamic, Max): 1536 MB 17:53:01 INFO - Vendor: Intel (0x8086) 17:53:01 INFO - Device ID: 0x0a2e 17:53:01 INFO - Revision ID: 0x0009 17:53:01 INFO - Displays: 17:53:01 INFO - Display: 17:53:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:53:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:53:01 INFO - Display Serial Number: 1600x1200 60 17:53:01 INFO - Main Display: Yes 17:53:01 INFO - Mirror: Off 17:53:01 INFO - Online: Yes 17:53:01 INFO - Rotation: Supported 17:53:01 INFO - Return code: 0 17:53:01 INFO - Running post-action listener: _resource_record_post_action 17:53:01 INFO - [mozharness: 2017-04-29 00:53:01.677114Z] Finished run-tests step (success) 17:53:01 INFO - Running post-run listener: _resource_record_post_run 17:53:01 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 39234048; Write bytes: 508059648; Read time: 252; Write time: 1616 17:53:01 INFO - TinderboxPrint: CPU usage
54.0% 17:53:01 INFO - TinderboxPrint: I/O read bytes / time
39,234,048 / 252 17:53:01 INFO - TinderboxPrint: I/O write bytes / time
508,059,648 / 1,616 17:53:01 INFO - TinderboxPrint: CPU idle
2,202.5 (46.0%) 17:53:01 INFO - TinderboxPrint: CPU system
960.8 (20.1%) 17:53:01 INFO - TinderboxPrint: CPU user
1,622.4 (33.9%) 17:53:01 INFO - TinderboxPrint: Swap in / out
247,967,744 / 0 17:53:01 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205238784; Write bytes: 205125632; Read time: 16089; Write time: 445 17:53:01 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 37255680; Write bytes: 296130560; Read time: 234; Write time: 1144 17:53:02 INFO - Running post-run listener: _upload_blobber_files 17:53:02 INFO - Blob upload gear active. 17:53:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:53:02 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:53:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:53:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:53:02 INFO - (blobuploader) - INFO - Open directory for files ... 17:53:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:53:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:53:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:53:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:53:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:53:04 INFO - (blobuploader) - INFO - Done attempting. 17:53:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:53:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:53:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:53:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:53:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:53:05 INFO - (blobuploader) - INFO - Done attempting. 17:53:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:53:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:53:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:53:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:53:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:53:06 INFO - (blobuploader) - INFO - Done attempting. 17:53:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:53:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:53:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:53:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:53:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:53:08 INFO - (blobuploader) - INFO - Done attempting. 17:53:08 INFO - (blobuploader) - INFO - Iteration through files over. 17:53:08 INFO - Return code: 0 17:53:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:53:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:53:08 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/647d6ccee8c99eb09d2897ae57553b75b30c018c7d6a5d62e3b25fb915c72e62f5085b029e1d8da542b63d1f741804605f16f732fc0174c34fa6a58ee7408e99", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e4d6b4ff5611947c9e00189fd73aa2395128c8978faf5e34c8253a58f860f3e8f096aa4269873eb2190099df27241e5d12a33530cddd8fff7b47263a14e7fc4f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65b89e57210453c82ead6b3508a9a588665073ede9b41f4710e111872e3bef58828f69777d72ca9e7f3212caf25d98f17e9f95887fb62114740c7fc6a7a244c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a1dbaa6a290e70ec171681d597445c983b427ee0305da4bf967a2e3b37b862738953d3b9414834a94c1cbc93d81e0d6e39c85a31c3e8600559154c0e4d3c458e"} 17:53:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:53:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:53:08 INFO - Contents: 17:53:08 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/647d6ccee8c99eb09d2897ae57553b75b30c018c7d6a5d62e3b25fb915c72e62f5085b029e1d8da542b63d1f741804605f16f732fc0174c34fa6a58ee7408e99", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e4d6b4ff5611947c9e00189fd73aa2395128c8978faf5e34c8253a58f860f3e8f096aa4269873eb2190099df27241e5d12a33530cddd8fff7b47263a14e7fc4f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65b89e57210453c82ead6b3508a9a588665073ede9b41f4710e111872e3bef58828f69777d72ca9e7f3212caf25d98f17e9f95887fb62114740c7fc6a7a244c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a1dbaa6a290e70ec171681d597445c983b427ee0305da4bf967a2e3b37b862738953d3b9414834a94c1cbc93d81e0d6e39c85a31c3e8600559154c0e4d3c458e"} 17:53:08 INFO - Running post-run listener: copy_logs_to_upload_dir 17:53:08 INFO - Copying logs to upload dir... 17:53:08 INFO - mkdir: /builds/slave/test/build/upload/logs 17:53:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.636642 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-04-28 17:53:08.433350) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-28 17:53:08.437192) ========= 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.iSqJqZHh47/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.O0H0AO6WUZ/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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/647d6ccee8c99eb09d2897ae57553b75b30c018c7d6a5d62e3b25fb915c72e62f5085b029e1d8da542b63d1f741804605f16f732fc0174c34fa6a58ee7408e99", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e4d6b4ff5611947c9e00189fd73aa2395128c8978faf5e34c8253a58f860f3e8f096aa4269873eb2190099df27241e5d12a33530cddd8fff7b47263a14e7fc4f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65b89e57210453c82ead6b3508a9a588665073ede9b41f4710e111872e3bef58828f69777d72ca9e7f3212caf25d98f17e9f95887fb62114740c7fc6a7a244c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a1dbaa6a290e70ec171681d597445c983b427ee0305da4bf967a2e3b37b862738953d3b9414834a94c1cbc93d81e0d6e39c85a31c3e8600559154c0e4d3c458e"} 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.011181 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/647d6ccee8c99eb09d2897ae57553b75b30c018c7d6a5d62e3b25fb915c72e62f5085b029e1d8da542b63d1f741804605f16f732fc0174c34fa6a58ee7408e99", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e4d6b4ff5611947c9e00189fd73aa2395128c8978faf5e34c8253a58f860f3e8f096aa4269873eb2190099df27241e5d12a33530cddd8fff7b47263a14e7fc4f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65b89e57210453c82ead6b3508a9a588665073ede9b41f4710e111872e3bef58828f69777d72ca9e7f3212caf25d98f17e9f95887fb62114740c7fc6a7a244c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a1dbaa6a290e70ec171681d597445c983b427ee0305da4bf967a2e3b37b862738953d3b9414834a94c1cbc93d81e0d6e39c85a31c3e8600559154c0e4d3c458e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-28 17:53:08.472630) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:53:08.472970) ========= 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.iSqJqZHh47/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.O0H0AO6WUZ/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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.005136 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:53:08.521429) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-28 17:53:08.521756) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-28 17:53:08.522076) ========= ========= Total master_lag: 0.19 =========