builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0391 starttime: 1488866385.33 results: success (0) buildid: 20170306215928 builduid: b7a9d388b302401d9c3bade538edb72a revision: fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-06 21:59:45.332998) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-06 21:59:45.333497) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-06 21:59:45.333828) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-06 21:59:45.360755) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-06 21:59:45.361075) ========= 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.iuV7vaJeqY/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.KZisH3BaMS/Listeners TMPDIR=/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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-03-06 21:59:45-- 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% 41.8M=0s 2017-03-06 21:59:45 (41.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.129053 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-06 21:59:45.511642) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-06 21:59:45.512019) ========= 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.iuV7vaJeqY/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.KZisH3BaMS/Listeners TMPDIR=/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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.025988 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-06 21:59:45.560661) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-06 21:59:45.561078) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd --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 fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.iuV7vaJeqY/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.KZisH3BaMS/Listeners TMPDIR=/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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-03-06 21:59:45,667 truncating revision to first 12 chars 2017-03-06 21:59:45,668 Setting DEBUG logging. 2017-03-06 21:59:45,668 attempt 1/10 2017-03-06 21:59:45,668 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fc9f6f7e8e86?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-06 21:59:47,912 unpacking tar archive at: mozilla-central-fc9f6f7e8e86/testing/mozharness/ program finished with exit code 0 elapsedTime=2.520144 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-06 21:59:48.104676) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-06 21:59:48.105006) ========= script_repo_revision: fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-06 21:59:48.105395) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-06 21:59:48.105661) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-06 21:59:48.125869) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-06 21:59:48.126177) ========= /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.iuV7vaJeqY/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.KZisH3BaMS/Listeners TMPDIR=/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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 21:59:48 INFO - MultiFileLogger online at 20170306 21:59:48 in /builds/slave/test 21:59:48 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 21:59:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:59:48 INFO - {'append_to_log': False, 21:59:48 INFO - 'base_work_dir': '/builds/slave/test', 21:59:48 INFO - 'blob_upload_branch': 'Firefox', 21:59:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:59:48 INFO - 'branch': 'Firefox', 21:59:48 INFO - 'buildbot_json_path': 'buildprops.json', 21:59:48 INFO - 'config_files': ('talos/mac_config.py',), 21:59:48 INFO - 'default_actions': ('clobber', 21:59:48 INFO - 'read-buildbot-config', 21:59:48 INFO - 'download-and-extract', 21:59:48 INFO - 'populate-webroot', 21:59:48 INFO - 'create-virtualenv', 21:59:48 INFO - 'install', 21:59:48 INFO - 'run-tests'), 21:59:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:59:48 INFO - 'download_minidump_stackwalk': True, 21:59:48 INFO - 'download_symbols': 'ondemand', 21:59:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:59:48 INFO - 'tooltool.py': '/tools/tooltool.py', 21:59:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:59:48 INFO - '/tools/misc-python/virtualenv.py')}, 21:59:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:59:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:59:48 INFO - 'installer_path': 'installer.exe', 21:59:48 INFO - 'log_level': 'info', 21:59:48 INFO - 'log_name': 'talos', 21:59:48 INFO - 'log_to_console': True, 21:59:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:59:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:59:48 INFO - 'opt_config_files': (), 21:59:48 INFO - 'pip_index': False, 21:59:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:59:48 INFO - 'cmd': ('bash', 21:59:48 INFO - '-c', 21:59:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:59:48 INFO - 'enabled': True, 21:59:48 INFO - 'halt_on_failure': False, 21:59:48 INFO - 'name': 'check_screen_resolution'},), 21:59:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:59:48 INFO - 'cmd': ('bash', 21:59:48 INFO - '-c', 21:59:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:59:48 INFO - 'enabled': True, 21:59:48 INFO - 'halt_on_failure': False, 21:59:48 INFO - 'name': 'check_screen_resolution'},), 21:59:48 INFO - 'run_cmd_checks_enabled': True, 21:59:48 INFO - 'sps_profile': False, 21:59:48 INFO - 'sps_profile_interval': 0, 21:59:48 INFO - 'suite': 'dromaeojs-e10s', 21:59:48 INFO - 'system_bits': '32', 21:59:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:59:48 INFO - 'title': 't-yosemite-r7-0391', 21:59:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:59:48 INFO - 'use_talos_json': True, 21:59:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:59:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:59:48 INFO - 'work_dir': 'build'} 21:59:48 INFO - [mozharness: 2017-03-07 05:59:48.343872Z] Running clobber step. 21:59:48 INFO - Running pre-action listener: _resource_record_pre_action 21:59:48 INFO - Running main action method: clobber 21:59:48 INFO - rmtree: /builds/slave/test/build 21:59:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:59:50 INFO - Running post-action listener: _resource_record_post_action 21:59:50 INFO - [mozharness: 2017-03-07 05:59:50.860957Z] Finished clobber step (success) 21:59:50 INFO - [mozharness: 2017-03-07 05:59:50.861090Z] Running read-buildbot-config step. 21:59:50 INFO - Running pre-action listener: _resource_record_pre_action 21:59:50 INFO - Running main action method: read_buildbot_config 21:59:50 INFO - Using buildbot properties: 21:59:50 INFO - { 21:59:50 INFO - "project": "", 21:59:50 INFO - "product": "firefox", 21:59:50 INFO - "script_repo_revision": "production", 21:59:50 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 21:59:50 INFO - "repository": "", 21:59:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 21:59:50 INFO - "buildid": "20170306215928", 21:59:50 INFO - "stage_platform": "macosx64", 21:59:50 INFO - "basedir": "/builds/slave/test", 21:59:50 INFO - "buildnumber": 100, 21:59:50 INFO - "platform": "yosemite_r7", 21:59:50 INFO - "mozci_request": { 21:59:50 INFO - "priority": -1, 21:59:50 INFO - "type": "trigger_all_talos_jobs", 21:59:50 INFO - "times": 1 21:59:50 INFO - }, 21:59:50 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 21:59:50 INFO - "slavebuilddir": "test", 21:59:50 INFO - "branch": "mozilla-central", 21:59:50 INFO - "revision": "fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd", 21:59:50 INFO - "slavename": "t-yosemite-r7-0391", 21:59:50 INFO - "builduid": "b7a9d388b302401d9c3bade538edb72a", 21:59:50 INFO - "repo_path": "mozilla-central" 21:59:50 INFO - } 21:59:50 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.dmg. 21:59:50 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.test_packages.json. 21:59:50 INFO - Running post-action listener: _resource_record_post_action 21:59:50 INFO - [mozharness: 2017-03-07 05:59:50.864810Z] Finished read-buildbot-config step (success) 21:59:50 INFO - [mozharness: 2017-03-07 05:59:50.864942Z] Running download-and-extract step. 21:59:50 INFO - Running pre-action listener: _resource_record_pre_action 21:59:50 INFO - Running main action method: download_and_extract 21:59:50 INFO - mkdir: /builds/slave/test/build/tests 21:59:50 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')]} 21:59:50 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.test_packages.json 21:59:50 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 21:59:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 21:59:51 INFO - Downloaded 1199 bytes. 21:59:51 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 21:59:51 INFO - Using the following test package requirements: 21:59:51 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 21:59:51 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 21:59:51 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 21:59:51 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 21:59:51 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 21:59:51 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 21:59:51 INFO - u'jsshell-mac.zip'], 21:59:51 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 21:59:51 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 21:59:51 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 21:59:51 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 21:59:51 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 21:59:51 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 21:59:51 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 21:59:51 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 21:59:51 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 21:59:51 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 21:59:51 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 21:59:51 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:59:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.common.tests.zip 21:59:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:59:51 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.common.tests.zip into memory 21:59:52 INFO - Expected file size: 51504348 21:59:52 INFO - Obtained file size: 51504348 22:00:08 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:00:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.common.tests.zip 22:00:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:00:08 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.common.tests.zip into memory 22:00:09 INFO - Expected file size: 51504348 22:00:09 INFO - Obtained file size: 51504348 22:00:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.talos.tests.zip 22:00:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:00:24 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 22:00:25 INFO - Expected file size: 14403647 22:00:25 INFO - Obtained file size: 14403647 22:00: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')]} 22:00:25 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.dmg 22:00:25 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 22:00:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 22:00:31 INFO - Downloaded 70417704 bytes. 22:00:31 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.dmg 22:00:31 INFO - mkdir: /builds/slave/test/properties 22:00:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:00:31 INFO - Writing to file /builds/slave/test/properties/build_url 22:00:31 INFO - Contents: 22:00:31 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.dmg 22:00:32 INFO - Running post-action listener: _resource_record_post_action 22:00:32 INFO - Running post-action listener: set_extra_try_arguments 22:00:32 INFO - [mozharness: 2017-03-07 06:00:32.094047Z] Finished download-and-extract step (success) 22:00:32 INFO - [mozharness: 2017-03-07 06:00:32.094218Z] Running populate-webroot step. 22:00:32 INFO - Running pre-action listener: _resource_record_pre_action 22:00:32 INFO - Running main action method: populate_webroot 22:00:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:00:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:00:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:00:32 INFO - '--tppagecycles', 22:00:32 INFO - '7'], 22:00:32 INFO - 'tests': ['tsvgm']}}, 22:00:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:00:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:00:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:00:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:00:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:00:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:00:32 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:00:32 INFO - 'talos_options': ['--disable-e10s'], 22:00:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:00:32 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:00:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:00:32 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:00:32 INFO - 'talos_options': ['--disable-e10s'], 22:00:32 INFO - 'tests': ['damp', 'tps']}, 22:00:32 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:00:32 INFO - 'tests': ['damp', 'tps']}, 22:00:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:00:32 INFO - 'tests': ['dromaeo_dom']}, 22:00:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:00:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:00:32 INFO - 'tests': ['basic_compositor_video']}, 22:00:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:00:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:00:32 INFO - 'tests': ['a11yr', 22:00:32 INFO - 'ts_paint', 22:00:32 INFO - 'tpaint', 22:00:32 INFO - 'sessionrestore', 22:00:32 INFO - 'sessionrestore_no_auto_restore', 22:00:32 INFO - 'tabpaint']}, 22:00:32 INFO - 'other-e10s': {'tests': ['a11yr', 22:00:32 INFO - 'ts_paint', 22:00:32 INFO - 'tpaint', 22:00:32 INFO - 'sessionrestore', 22:00:32 INFO - 'sessionrestore_no_auto_restore', 22:00:32 INFO - 'tabpaint']}, 22:00:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:00:32 INFO - 'ts_paint', 22:00:32 INFO - 'tpaint', 22:00:32 INFO - 'sessionrestore', 22:00:32 INFO - 'sessionrestore_no_auto_restore', 22:00:32 INFO - 'tabpaint']}, 22:00:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:00:32 INFO - 'ts_paint', 22:00:32 INFO - 'tpaint', 22:00:32 INFO - 'sessionrestore', 22:00:32 INFO - 'sessionrestore_no_auto_restore', 22:00:32 INFO - 'tabpaint']}, 22:00:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:00:32 INFO - 'tests': ['a11yr', 22:00:32 INFO - 'ts_paint', 22:00:32 INFO - 'tpaint', 22:00:32 INFO - 'sessionrestore', 22:00:32 INFO - 'sessionrestore_no_auto_restore', 22:00:32 INFO - 'tabpaint']}, 22:00:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:00:32 INFO - 'tests': ['a11yr', 22:00:32 INFO - 'ts_paint', 22:00:32 INFO - 'tpaint', 22:00:32 INFO - 'sessionrestore', 22:00:32 INFO - 'sessionrestore_no_auto_restore', 22:00:32 INFO - 'tabpaint']}, 22:00:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:00:32 INFO - 'tests': ['tsvgx', 22:00:32 INFO - 'tsvgr_opacity', 22:00:32 INFO - 'tart', 22:00:32 INFO - 'tscrollx', 22:00:32 INFO - 'cart', 22:00:32 INFO - 'tsvg_static']}, 22:00:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:00:32 INFO - 'tsvgr_opacity', 22:00:32 INFO - 'tart', 22:00:32 INFO - 'tscrollx', 22:00:32 INFO - 'cart', 22:00:32 INFO - 'tsvg_static']}, 22:00:32 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:00:32 INFO - 'talos_options': ['--disable-e10s'], 22:00:32 INFO - 'tests': ['tp5o']}, 22:00:32 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:00:32 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:00:32 INFO - 'talos_options': ['--disable-e10s', 22:00:32 INFO - '--xperf_path', 22:00:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:00:32 INFO - 'tests': ['tp5n']}, 22:00:32 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:00:32 INFO - 'talos_options': ['--xperf_path', 22:00:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:00:32 INFO - 'tests': ['tp5n']}}, 22:00:32 INFO - 'talos.zip': {'path': '', 22:00:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:00:32 INFO - Running post-action listener: _resource_record_post_action 22:00:32 INFO - [mozharness: 2017-03-07 06:00:32.113219Z] Finished populate-webroot step (success) 22:00:32 INFO - [mozharness: 2017-03-07 06:00:32.113367Z] Running create-virtualenv step. 22:00:32 INFO - Running pre-action listener: _resource_record_pre_action 22:00:32 INFO - Running main action method: create_virtualenv 22:00:32 INFO - Creating virtualenv /builds/slave/test/build/venv 22:00:32 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 22:00:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:00:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:00:32 INFO - Using real prefix '/tools/python27' 22:00:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:00:32 INFO - Installing distribute.............................................................................................................................................................................................done. 22:00:35 INFO - Installing pip.................done. 22:00:35 INFO - Return code: 0 22:00:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:00:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:00:36 INFO - Reading from file tmpfile_stdout 22:00:36 INFO - Output received: 22:00:36 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) 22:00:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:00:36 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')]} 22:00:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:36 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')]} 22:00:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:36 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 0x107a04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f814259e070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077e2d30>, '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 0x107709db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1077d7be8>, '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.iuV7vaJeqY/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.KZisH3BaMS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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 22:00:36 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 22:00:36 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 22:00:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iuV7vaJeqY/Render', 22:00:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:00:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:00:36 INFO - 'HOME': '/Users/cltbld', 22:00:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:00:36 INFO - 'LOGNAME': 'cltbld', 22:00:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:36 INFO - 'MOZ_NO_REMOTE': '1', 22:00:36 INFO - 'NO_EM_RESTART': '1', 22:00:36 INFO - 'PAGER': '/bin/cat', 22:00:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:00:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:36 INFO - 'PWD': '/builds/slave/test', 22:00:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:00:36 INFO - 'SHELL': '/bin/bash', 22:00:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZisH3BaMS/Listeners', 22:00:36 INFO - 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/', 22:00:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:00:36 INFO - 'USER': 'cltbld', 22:00:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:00:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:00:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:36 INFO - 'XPC_FLAGS': '0x0', 22:00:36 INFO - 'XPC_SERVICE_NAME': '0', 22:00:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:00:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:37 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 22:00:37 INFO - Cleaning up... 22:00:37 INFO - Return code: 0 22:00:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:00:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:00:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:00:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x107a04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f814259e070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077e2d30>, '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 0x107709db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1077d7be8>, '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.iuV7vaJeqY/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.KZisH3BaMS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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 22:00:37 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 22:00:37 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 22:00:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iuV7vaJeqY/Render', 22:00:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:00:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:00:37 INFO - 'HOME': '/Users/cltbld', 22:00:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:00:37 INFO - 'LOGNAME': 'cltbld', 22:00:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:37 INFO - 'MOZ_NO_REMOTE': '1', 22:00:37 INFO - 'NO_EM_RESTART': '1', 22:00:37 INFO - 'PAGER': '/bin/cat', 22:00:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:00:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:37 INFO - 'PWD': '/builds/slave/test', 22:00:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:00:37 INFO - 'SHELL': '/bin/bash', 22:00:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZisH3BaMS/Listeners', 22:00:37 INFO - 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/', 22:00:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:00:37 INFO - 'USER': 'cltbld', 22:00:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:00:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:00:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:37 INFO - 'XPC_FLAGS': '0x0', 22:00:37 INFO - 'XPC_SERVICE_NAME': '0', 22:00:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:00:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:37 INFO - Downloading/unpacking psutil>=3.1.1 22:00:37 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 22:00:37 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 22:00:37 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 22:00:37 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 22:00:37 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 22:00:37 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 22:00:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:00:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:00:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:00:41 INFO - Installing collected packages: psutil 22:00:41 INFO - Running setup.py install for psutil 22:00:41 INFO - building 'psutil._psutil_osx' extension 22:00:41 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 22:00:41 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 22:00:42 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 22:00:42 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 22:00:42 INFO - building 'psutil._psutil_posix' extension 22:00:42 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 22:00:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:00:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:00:42 INFO - ^ 22:00:42 INFO - 1 warning generated. 22:00:42 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 22:00:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:00:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:00:42 INFO - Successfully installed psutil 22:00:42 INFO - Cleaning up... 22:00:42 INFO - Return code: 0 22:00:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:00:42 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')]} 22:00:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:42 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')]} 22:00:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:42 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 0x107a04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f814259e070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077e2d30>, '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 0x107709db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1077d7be8>, '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.iuV7vaJeqY/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.KZisH3BaMS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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 22:00:42 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 22:00:42 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 22:00:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iuV7vaJeqY/Render', 22:00:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:00:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:00:42 INFO - 'HOME': '/Users/cltbld', 22:00:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:00:42 INFO - 'LOGNAME': 'cltbld', 22:00:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:42 INFO - 'MOZ_NO_REMOTE': '1', 22:00:42 INFO - 'NO_EM_RESTART': '1', 22:00:42 INFO - 'PAGER': '/bin/cat', 22:00:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:00:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:42 INFO - 'PWD': '/builds/slave/test', 22:00:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:00:42 INFO - 'SHELL': '/bin/bash', 22:00:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZisH3BaMS/Listeners', 22:00:42 INFO - 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/', 22:00:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:00:42 INFO - 'USER': 'cltbld', 22:00:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:00:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:00:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:42 INFO - 'XPC_FLAGS': '0x0', 22:00:42 INFO - 'XPC_SERVICE_NAME': '0', 22:00:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:00:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:00:42 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 22:00:42 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 22:00:42 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 22:00:42 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 22:00:42 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 22:00:42 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 22:00:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:00:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:00:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:00:45 INFO - Installing collected packages: mozsystemmonitor 22:00:45 INFO - Running setup.py install for mozsystemmonitor 22:00:45 INFO - Successfully installed mozsystemmonitor 22:00:45 INFO - Cleaning up... 22:00:45 INFO - Return code: 0 22:00:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:00:45 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')]} 22:00:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:45 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')]} 22:00:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x107a04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f814259e070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077e2d30>, '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 0x107709db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1077d7be8>, '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.iuV7vaJeqY/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.KZisH3BaMS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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 22:00:45 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 22:00:45 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 22:00:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iuV7vaJeqY/Render', 22:00:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:00:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:00:45 INFO - 'HOME': '/Users/cltbld', 22:00:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:00:45 INFO - 'LOGNAME': 'cltbld', 22:00:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:45 INFO - 'MOZ_NO_REMOTE': '1', 22:00:45 INFO - 'NO_EM_RESTART': '1', 22:00:45 INFO - 'PAGER': '/bin/cat', 22:00:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:00:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:45 INFO - 'PWD': '/builds/slave/test', 22:00:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:00:45 INFO - 'SHELL': '/bin/bash', 22:00:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZisH3BaMS/Listeners', 22:00:45 INFO - 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/', 22:00:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:00:45 INFO - 'USER': 'cltbld', 22:00:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:00:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:00:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:45 INFO - 'XPC_FLAGS': '0x0', 22:00:45 INFO - 'XPC_SERVICE_NAME': '0', 22:00:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:00:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:46 INFO - Downloading/unpacking jsonschema==2.5.1 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:00:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00:49 INFO - Downloading functools32-3.2.3-2.tar.gz 22:00:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:00:49 INFO - warning: no files found matching '*.txt' 22:00:49 INFO - no previously-included directories found matching 'build' 22:00:49 INFO - no previously-included directories found matching 'dist' 22:00:49 INFO - no previously-included directories found matching '.git*' 22:00:49 INFO - Installing collected packages: jsonschema, functools32 22:00:49 INFO - Running setup.py install for functools32 22:00:49 INFO - warning: no files found matching '*.txt' 22:00:49 INFO - no previously-included directories found matching 'build' 22:00:49 INFO - no previously-included directories found matching 'dist' 22:00:49 INFO - no previously-included directories found matching '.git*' 22:00:49 INFO - Successfully installed jsonschema functools32 22:00:49 INFO - Cleaning up... 22:00:49 INFO - Return code: 0 22:00:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:00:49 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')]} 22:00:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:49 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')]} 22:00:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x107a04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f814259e070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077e2d30>, '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 0x107709db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1077d7be8>, '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.iuV7vaJeqY/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.KZisH3BaMS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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 22:00:49 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 22:00:49 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 22:00:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iuV7vaJeqY/Render', 22:00:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:00:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:00:49 INFO - 'HOME': '/Users/cltbld', 22:00:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:00:49 INFO - 'LOGNAME': 'cltbld', 22:00:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:49 INFO - 'MOZ_NO_REMOTE': '1', 22:00:49 INFO - 'NO_EM_RESTART': '1', 22:00:49 INFO - 'PAGER': '/bin/cat', 22:00:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:00:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:49 INFO - 'PWD': '/builds/slave/test', 22:00:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:00:49 INFO - 'SHELL': '/bin/bash', 22:00:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZisH3BaMS/Listeners', 22:00:49 INFO - 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/', 22:00:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:00:49 INFO - 'USER': 'cltbld', 22:00:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:00:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:00:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:49 INFO - 'XPC_FLAGS': '0x0', 22:00:49 INFO - 'XPC_SERVICE_NAME': '0', 22:00:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:00:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:00:49 INFO - Cleaning up... 22:00:49 INFO - Return code: 0 22:00:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:00:49 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')]} 22:00:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:49 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')]} 22:00:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x107a04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f814259e070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077e2d30>, '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 0x107709db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1077d7be8>, '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.iuV7vaJeqY/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.KZisH3BaMS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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 22:00:49 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 22:00:49 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 22:00:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iuV7vaJeqY/Render', 22:00:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:00:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:00:49 INFO - 'HOME': '/Users/cltbld', 22:00:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:00:49 INFO - 'LOGNAME': 'cltbld', 22:00:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:49 INFO - 'MOZ_NO_REMOTE': '1', 22:00:49 INFO - 'NO_EM_RESTART': '1', 22:00:49 INFO - 'PAGER': '/bin/cat', 22:00:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:00:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:49 INFO - 'PWD': '/builds/slave/test', 22:00:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:00:49 INFO - 'SHELL': '/bin/bash', 22:00:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZisH3BaMS/Listeners', 22:00:49 INFO - 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/', 22:00:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:00:49 INFO - 'USER': 'cltbld', 22:00:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:00:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:00:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:49 INFO - 'XPC_FLAGS': '0x0', 22:00:49 INFO - 'XPC_SERVICE_NAME': '0', 22:00:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:00:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:50 INFO - Downloading/unpacking blobuploader==1.2.4 22:00:50 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 22:00:50 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 22:00:50 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 22:00:50 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 22:00:50 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 22:00:50 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 22:00:52 INFO - Downloading blobuploader-1.2.4.tar.gz 22:00:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:00:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:00:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:53 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 22:00:53 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 22:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:53 INFO - Downloading docopt-0.6.1.tar.gz 22:00:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:00:54 INFO - Installing collected packages: blobuploader, requests, docopt 22:00:54 INFO - Running setup.py install for blobuploader 22:00:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:00:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:00:54 INFO - Running setup.py install for requests 22:00:54 INFO - Running setup.py install for docopt 22:00:54 INFO - Successfully installed blobuploader requests docopt 22:00:54 INFO - Cleaning up... 22:00:54 INFO - Return code: 0 22:00:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:00:54 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')]} 22:00:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:54 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')]} 22:00:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x107a04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f814259e070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077e2d30>, '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 0x107709db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1077d7be8>, '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.iuV7vaJeqY/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.KZisH3BaMS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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 22:00:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 22:00:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.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 22:00:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iuV7vaJeqY/Render', 22:00:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:00:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:00:54 INFO - 'HOME': '/Users/cltbld', 22:00:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:00:54 INFO - 'LOGNAME': 'cltbld', 22:00:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:54 INFO - 'MOZ_NO_REMOTE': '1', 22:00:54 INFO - 'NO_EM_RESTART': '1', 22:00:54 INFO - 'PAGER': '/bin/cat', 22:00:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:00:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:54 INFO - 'PWD': '/builds/slave/test', 22:00:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:00:54 INFO - 'SHELL': '/bin/bash', 22:00:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZisH3BaMS/Listeners', 22:00:54 INFO - 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/', 22:00:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:00:54 INFO - 'USER': 'cltbld', 22:00:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:00:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:00:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:54 INFO - 'XPC_FLAGS': '0x0', 22:00:54 INFO - 'XPC_SERVICE_NAME': '0', 22:00:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:00:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:00:55 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-mU9cnt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:00:55 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-bUvrQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:00:55 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-Uq6_Fp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:00:55 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-tUV6uo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:00:55 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-tDgryV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:00:55 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-z6wsOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:00:55 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-5v3wIf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:00:55 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-TNEqhO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:00:55 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-kbUPiT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:00:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:00:56 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-JfiVEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:00:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:00:56 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-QV1aW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:00:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:00:56 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-V5zZnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:00:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:00:56 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-Edqq2U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:00:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:00:56 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-kH4NcT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:00:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:00:56 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-VcwMnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:00:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:00:56 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-ohgwU7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:00:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:00:56 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-in7sSd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:00:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:00:56 INFO - Running setup.py install for manifestparser 22:00:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:00:57 INFO - Running setup.py install for mozcrash 22:00:57 INFO - Running setup.py install for mozdebug 22:00:57 INFO - Running setup.py install for mozdevice 22:00:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:00:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:00:57 INFO - Running setup.py install for mozfile 22:00:57 INFO - Running setup.py install for mozhttpd 22:00:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:00:57 INFO - Running setup.py install for mozinfo 22:00:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:00:57 INFO - Running setup.py install for mozInstall 22:00:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:00:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:00:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:00:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:00:58 INFO - Running setup.py install for mozleak 22:00:58 INFO - Running setup.py install for mozlog 22:00:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:00:58 INFO - Running setup.py install for moznetwork 22:00:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:00:58 INFO - Running setup.py install for mozprocess 22:00:58 INFO - Running setup.py install for mozprofile 22:00:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:00:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:00:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:00:58 INFO - Running setup.py install for mozrunner 22:00:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:00:59 INFO - Running setup.py install for mozscreenshot 22:00:59 INFO - Running setup.py install for moztest 22:00:59 INFO - Running setup.py install for mozversion 22:00:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:00:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:00:59 INFO - Cleaning up... 22:00:59 INFO - Return code: 0 22:00:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:00:59 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')]} 22:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:59 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')]} 22:00:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x107a04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f814259e070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077e2d30>, '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 0x107709db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1077d7be8>, '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.iuV7vaJeqY/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.KZisH3BaMS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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 22:00:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 22:00:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.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 22:00:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iuV7vaJeqY/Render', 22:00:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:00:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:00:59 INFO - 'HOME': '/Users/cltbld', 22:00:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:00:59 INFO - 'LOGNAME': 'cltbld', 22:00:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:59 INFO - 'MOZ_NO_REMOTE': '1', 22:00:59 INFO - 'NO_EM_RESTART': '1', 22:00:59 INFO - 'PAGER': '/bin/cat', 22:00:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:00:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:59 INFO - 'PWD': '/builds/slave/test', 22:00:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:00:59 INFO - 'SHELL': '/bin/bash', 22:00:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZisH3BaMS/Listeners', 22:00:59 INFO - 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/', 22:00:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:00:59 INFO - 'USER': 'cltbld', 22:00:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:00:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:00:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:59 INFO - 'XPC_FLAGS': '0x0', 22:00:59 INFO - 'XPC_SERVICE_NAME': '0', 22:00:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:00:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:00:59 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-NckYVX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:00:59 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:00:59 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-ZCY9G7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:00:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:00:59 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-BmjGL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:01:00 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)) 22:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:01:00 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-cME7YB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:01:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 22:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:01:00 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-6Wm_13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:01:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:01:00 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-ry9kGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:01:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:01:00 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-d3T71q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:01:00 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)) 22:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:01:00 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-OpxGyh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:01:00 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)) 22:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:01:00 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-DdtPaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:01:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:01:00 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-SHsZUK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:01:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:01:00 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-Q22PCO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:01:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:01:00 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-zy8OLy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:01:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 22:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:01:01 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-msQ4_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:01:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:01:01 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-vjnI7c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:01:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:01 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-bWubSt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:01:01 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-zZaiEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:01:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:01:01 INFO - Running setup.py (path:/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/pip-FQENH1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:01:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:01:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:01:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:01:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:01:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:01:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:01:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:01:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:01 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 22:01:01 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 22:01:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:04 INFO - Downloading blessings-1.6.tar.gz 22:01:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:01:04 INFO - Installing collected packages: blessings 22:01:04 INFO - Running setup.py install for blessings 22:01:04 INFO - Successfully installed blessings 22:01:04 INFO - Cleaning up... 22:01:04 INFO - Return code: 0 22:01:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:01:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:01:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:01:04 INFO - Reading from file tmpfile_stdout 22:01:04 INFO - Current package versions: 22:01:04 INFO - blessings == 1.6 22:01:04 INFO - blobuploader == 1.2.4 22:01:04 INFO - docopt == 0.6.1 22:01:04 INFO - functools32 == 3.2.3-2 22:01:04 INFO - jsonschema == 2.5.1 22:01:04 INFO - manifestparser == 1.1 22:01:04 INFO - mozInstall == 1.12 22:01:04 INFO - mozcrash == 1.0 22:01:04 INFO - mozdebug == 0.1 22:01:04 INFO - mozdevice == 0.49 22:01:04 INFO - mozfile == 1.2 22:01:04 INFO - mozhttpd == 0.7 22:01:04 INFO - mozinfo == 0.9 22:01:04 INFO - mozleak == 0.1 22:01:04 INFO - mozlog == 3.4 22:01:04 INFO - moznetwork == 0.27 22:01:04 INFO - mozprocess == 0.24 22:01:04 INFO - mozprofile == 0.28 22:01:04 INFO - mozrunner == 6.13 22:01:04 INFO - mozscreenshot == 0.1 22:01:04 INFO - mozsystemmonitor == 0.3 22:01:04 INFO - moztest == 0.8 22:01:04 INFO - mozversion == 1.4 22:01:04 INFO - psutil == 3.1.1 22:01:04 INFO - requests == 1.2.3 22:01:04 INFO - wsgiref == 0.1.2 22:01:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01: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')]} 22:01:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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')]} 22:01:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:04 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 0x107a04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f814259e070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077e2d30>, '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 0x107709db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1077d7be8>, '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.iuV7vaJeqY/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.KZisH3BaMS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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 22:01:04 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 22:01:04 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 22:01:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iuV7vaJeqY/Render', 22:01:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:01:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:01:04 INFO - 'HOME': '/Users/cltbld', 22:01:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:01:04 INFO - 'LOGNAME': 'cltbld', 22:01:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:04 INFO - 'MOZ_NO_REMOTE': '1', 22:01:04 INFO - 'NO_EM_RESTART': '1', 22:01:04 INFO - 'PAGER': '/bin/cat', 22:01:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:01:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:04 INFO - 'PWD': '/builds/slave/test', 22:01:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:01:04 INFO - 'SHELL': '/bin/bash', 22:01:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZisH3BaMS/Listeners', 22:01:04 INFO - 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/', 22:01:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:01:04 INFO - 'USER': 'cltbld', 22:01:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:01:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:01:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:04 INFO - 'XPC_FLAGS': '0x0', 22:01:04 INFO - 'XPC_SERVICE_NAME': '0', 22:01:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:01:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:05 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)) 22:01:05 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)) 22:01:05 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)) 22:01:05 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)) 22:01:05 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)) 22:01:05 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)) 22:01:05 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)) 22:01:05 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)) 22:01:05 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)) 22:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:01:05 INFO - Cleaning up... 22:01:05 INFO - Return code: 0 22:01:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:01:05 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')]} 22:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:05 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')]} 22:01:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x107a04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f814259e070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077e2d30>, '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 0x107709db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1077d7be8>, '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.iuV7vaJeqY/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.KZisH3BaMS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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 22:01:05 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 22:01:05 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 22:01:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iuV7vaJeqY/Render', 22:01:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:01:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:01:05 INFO - 'HOME': '/Users/cltbld', 22:01:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:01:05 INFO - 'LOGNAME': 'cltbld', 22:01:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:05 INFO - 'MOZ_NO_REMOTE': '1', 22:01:05 INFO - 'NO_EM_RESTART': '1', 22:01:05 INFO - 'PAGER': '/bin/cat', 22:01:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:01:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:05 INFO - 'PWD': '/builds/slave/test', 22:01:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:01:05 INFO - 'SHELL': '/bin/bash', 22:01:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZisH3BaMS/Listeners', 22:01:05 INFO - 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/', 22:01:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:01:05 INFO - 'USER': 'cltbld', 22:01:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:01:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:01:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:05 INFO - 'XPC_FLAGS': '0x0', 22:01:05 INFO - 'XPC_SERVICE_NAME': '0', 22:01:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:01:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:01:05 INFO - Cleaning up... 22:01:05 INFO - Return code: 0 22:01:05 INFO - Running post-action listener: _resource_record_post_action 22:01:05 INFO - Running post-action listener: _start_resource_monitoring 22:01:05 INFO - Starting resource monitoring. 22:01:05 INFO - [mozharness: 2017-03-07 06:01:05.420017Z] Finished create-virtualenv step (success) 22:01:05 INFO - [mozharness: 2017-03-07 06:01:05.421035Z] Running install step. 22:01:05 INFO - Running pre-action listener: _resource_record_pre_action 22:01:05 INFO - Running main action method: install 22:01:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:01:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:01:05 INFO - Reading from file tmpfile_stdout 22:01:05 INFO - Detecting whether we're running mozinstall >=1.0... 22:01:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:01:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:01:05 INFO - Reading from file tmpfile_stdout 22:01:05 INFO - Output received: 22:01:05 INFO - Usage: mozinstall [options] installer 22:01:05 INFO - Options: 22:01:05 INFO - -h, --help show this help message and exit 22:01:05 INFO - -d DEST, --destination=DEST 22:01:05 INFO - Directory to install application into. [default: 22:01:05 INFO - "/builds/slave/test"] 22:01:05 INFO - --app=APP Application being installed. [default: firefox] 22:01:05 INFO - mkdir: /builds/slave/test/build/application 22:01:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:01:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:01:27 INFO - Reading from file tmpfile_stdout 22:01:27 INFO - Output received: 22:01:27 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:01:27 INFO - Running post-action listener: _resource_record_post_action 22:01:27 INFO - [mozharness: 2017-03-07 06:01:27.579107Z] Finished install step (success) 22:01:27 INFO - [mozharness: 2017-03-07 06:01:27.579232Z] Running run-tests step. 22:01:27 INFO - Running pre-action listener: _resource_record_pre_action 22:01:27 INFO - Running main action method: run_tests 22:01:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:01:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:01:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:01:27 INFO - 2017-03-06 22:01:27.593 screenresolution[1622:7467] starting screenresolution argv=screenresolution get 22:01:27 INFO - 2017-03-06 22:01:27.596 screenresolution[1622:7467] Display 0: 1600x1200x32@60 22:01:27 INFO - 2017-03-06 22:01:27.602 screenresolution[1623:7470] starting screenresolution argv=screenresolution list 22:01:27 INFO - Available Modes on Display 0 22:01:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:01:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:01:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:01:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:01:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:01:27 INFO - Intel Iris: 22:01:27 INFO - Chipset Model: Intel Iris 22:01:27 INFO - Type: GPU 22:01:27 INFO - Bus: Built-In 22:01:27 INFO - VRAM (Dynamic, Max): 1536 MB 22:01:27 INFO - Vendor: Intel (0x8086) 22:01:27 INFO - Device ID: 0x0a2e 22:01:27 INFO - Revision ID: 0x0009 22:01:27 INFO - Displays: 22:01:27 INFO - Display: 22:01:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:01:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:01:27 INFO - Display Serial Number: 1600x1200 60 22:01:27 INFO - Main Display: Yes 22:01:27 INFO - Mirror: Off 22:01:27 INFO - Online: Yes 22:01:27 INFO - Rotation: Supported 22:01:27 INFO - Return code: 0 22:01:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:01:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:01:27 INFO - Python 2.7.3 22:01:27 INFO - Return code: 0 22:01:27 INFO - grabbing minidump binary from tooltool 22:01:27 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')]} 22:01:27 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 0x1077e2d30>, '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 0x107709db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1077d7be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:01:27 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 22:01:27 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 22:01:27 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 22:01:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:01:27 INFO - Return code: 0 22:01:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:01:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:01:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:01:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:01:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:01:27 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/1487085285/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0391', '--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 22:01:27 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/1487085285/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0391 --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 22:01:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iuV7vaJeqY/Render', 22:01:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:01:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:01:27 INFO - 'HOME': '/Users/cltbld', 22:01:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:01:27 INFO - 'LOGNAME': 'cltbld', 22:01:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:01:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:01:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:27 INFO - 'MOZ_NO_REMOTE': '1', 22:01:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:01:27 INFO - 'NO_EM_RESTART': '1', 22:01:27 INFO - 'PAGER': '/bin/cat', 22:01:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:01:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:27 INFO - 'PWD': '/builds/slave/test', 22:01:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:01:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:01:27 INFO - 'SHELL': '/bin/bash', 22:01:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZisH3BaMS/Listeners', 22:01:27 INFO - 'TMPDIR': '/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/', 22:01:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:01:27 INFO - 'USER': 'cltbld', 22:01:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:01:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:01:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:27 INFO - 'XPC_FLAGS': '0x0', 22:01:27 INFO - 'XPC_SERVICE_NAME': '0', 22:01:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:01:27 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/1487085285/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0391', '--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 22:01:28 INFO - mozversion application_buildid: 20170214071445 22:01:28 INFO - mozversion application_changeset: fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd 22:01:28 INFO - mozversion application_display_name: Nightly 22:01:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:01:28 INFO - mozversion application_name: Firefox 22:01:28 INFO - mozversion application_remotingname: firefox 22:01:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:01:28 INFO - mozversion application_vendor: Mozilla 22:01:28 INFO - mozversion application_version: 54.0a1 22:01:28 INFO - mozversion platform_buildid: 20170214071445 22:01:28 INFO - mozversion platform_changeset: fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd 22:01:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:01:28 INFO - mozversion platform_version: 54.0a1 22:01:28 INFO - using testdate: 1488866488 22:01:28 INFO - actual date: 1488866488 22:01:28 INFO - starting webserver on 'localhost:49238' 22:01:28 INFO - SUITE-START | Running 2 tests 22:01:28 INFO - TEST-START | dromaeo_css 22:01:28 INFO - operating with platform_type : mac_ 22:01:28 INFO - Initialising browser for dromaeo_css test... 22:01:28 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/tmpY_9Pwn/profile http://localhost:49238/getInfo.html) 22:01:30 INFO - PROCESS | 1633 | 2017-03-06 22:01:30.943 plugin-container[1635:7622] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3b, name = 'com.apple.tsm.portname' 22:01:30 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:01:30 INFO - PROCESS | 1633 | 2017-03-06 22:01:30.946 plugin-container[1635:7622] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 22:01:30 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:01:30 INFO - PROCESS | 1633 | 2017-03-06 22:01:30.946 plugin-container[1635:7622] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:01:31 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 22:01:31 INFO - PROCESS | 1633 | colorDepth:24 22:01:31 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 22:01:31 INFO - PROCESS | 1633 | __metrics 22:01:31 ERROR - PROCESS | 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 22:01:31 INFO - TEST-INFO | 1633: exit 0 22:01:31 INFO - Browser initialized. 22:01:31 INFO - Running cycle 1/1 for dromaeo_css test... 22:01:31 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/tmpY_9Pwn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:01:34 INFO - PROCESS | 1639 | 2017-03-06 22:01:34.605 plugin-container[1640:7806] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b47, name = 'com.apple.tsm.portname' 22:01:34 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:01:34 INFO - PROCESS | 1639 | 2017-03-06 22:01:34.605 plugin-container[1640:7806] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 22:01:34 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:01:34 INFO - PROCESS | 1639 | 2017-03-06 22:01:34.606 plugin-container[1640:7806] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:01:44 INFO - PROCESS | 1639 | RSS: Main: 232927232 22:01:44 INFO - PROCESS | 1639 | 22:04:47 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49238/tests/dromaeo/cssquery-ext.html) 22:04:49 INFO - PROCESS | 1639 | RSS: Main: 255725568 22:04:49 INFO - PROCESS | 1639 | 22:06:32 INFO - PROCESS | 1639 | ************************* 22:06:32 INFO - PROCESS | 1639 | A coding exception was thrown and uncaught in a Task. 22:06:32 INFO - PROCESS | 1639 | 22:06:32 ERROR - PROCESS | 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:06:32 INFO - PROCESS | 1639 | Full stack: 22:06:32 INFO - PROCESS | 1639 | ************************* 22:07:51 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-ext.html (next: http://localhost:49238/tests/dromaeo/cssquery-jquery.html) 22:07:52 INFO - PROCESS | 1639 | RSS: Main: 260542464 22:07:52 INFO - PROCESS | 1639 | 22:10:54 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49238/tests/dromaeo/cssquery-mootools.html) 22:10:54 INFO - PROCESS | 1639 | RSS: Main: 271429632 22:10:54 INFO - PROCESS | 1639 | 22:13:56 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49238/tests/dromaeo/cssquery-prototype.html) 22:13:57 INFO - PROCESS | 1639 | RSS: Main: 264232960 22:13:57 INFO - PROCESS | 1639 | 22:17:00 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49238/tests/dromaeo/cssquery-yui.html) 22:17:00 INFO - PROCESS | 1639 | RSS: Main: 268161024 22:17:00 INFO - PROCESS | 1639 | 22:20:04 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-yui.html (next: http://localhost:49238/tests/dromaeo/cssquery-dojo.html) 22:20:04 INFO - PROCESS | 1639 | RSS: Main: 269197312 22:20:04 INFO - PROCESS | 1639 | 22:20:04 INFO - PROCESS | 1639 | __start_tp_report 22:20:04 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 22:20:04 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 22:20:04 INFO - PROCESS | 1639 | |i|pagename|runs| 22:20:04 INFO - PROCESS | 1639 | |0;dojo.html;3126.5153901145322;3317.4692049272107;3336.6162891267513;3383.5939687237537;3402.8638854445817;5450.209719590657;5553.7778488860395;5605;5611.130274807406;5653.802116925908;4514.390557274775;5470.370907345657;5512.145617429295;5554.805581804628;5563.221149039137;4978.6514943953935;5071.1886098224295;5120.4623514530995;5150.4334523202415;5156.407013193493;4976.104301225796;5154.639175257734;5217;5238;5374.8925021499745;5003.949960500385;5072.51811077947;5092.745362731848;5159.613029022819;5200.635955483118;5135.897282054375;5137.229415587654;5141.665791723563;5165.096108181091;5170.1986192140275;5099;5103.336566246401;5120.641555091145;5137.255098010767;5161.509656582619;5279.445658205871;5451.700156491392;5454.699991500466;5524.33707955044;5600.439956004408;6131.346082861964;6165.445109940082;6166.075088736726;6166.630002199838;6209.316975132731;6186.907196392058;6191.535634827406;6197.783077592308;6198.2252218472695;6216.844578885519;5555.138953462219;5629.887402252014;5747.626404283708;5762.250907382056;5790.884182316372;6147.293061297919;6212.06818977148;6215.96892015537;6218.844528886814;6223.439890409885;5757.88484230313;5848.0350742127075;6049.788257411057;6050.45545900871;6077.179580756541;6038.969805151034;6104.7926496150985;6106.572539922163;6137.202163718688;6147.432404736853;6173.876522469615;6176.227971503562;6181.072839074085;6183.783567575113;6211.59624624407;5930.762769489258;5997.950358687212;6003.129546215774;6034.456898879122;6054.576179667469;4841.975790121027;4856.80572777092;4870.245112007645;4886.657933944661;4943;4362.956370436318;4368.650507959355;4387.890302742393;4393.077453734688;4427.269500532376;4667.906641867212;4748.406449193851;5684.488396044376;5685.403032681509;5702.8289151325525;4983.551480366712;5098.719570423662;5128.794848206105;5310.893782124336;6191.133241346125;5732.398098199712;5735.541156707453;5767.596268261182;5795.1017592273265;5799.246098007232;4241.851535196253;5295.099833028395;5408.702521361284;5432.076546986943;5480.561555075585;5119.718415487184;6110.2550082019825;6135.7839197735275;6157.953147964767;6248.937510624835;5064.417591976822;6103.792276408762;6122.693865306628;6125.510168486406;6153.169322141453;6163.260408750979;6185.010398336244;6189.752409903554;6191.349908259568;6194.783182588588;5885.793997210077;5899.410058994066;5948.613340132878;5996.610220335664;6035.215421995113;6102.420270074336;6121.818815678001;6137.048757442603;6144.170536977451;6254.9687251563455;6017.909731354121;6037.611349389577;6052.546059045501;6068.544859135671;6071.969640151771;5776.277965254343;5836.299644042742;5883.61756485827;5921.940780592311;5965.164876917148;5659.54723622101;5733.655980641175;5762.510186634183;5762.567807414544;5770.451807078321;5902.6458412495385;5926.614770039933;5934.19888315072;5947.821565353046;5976.9103463448955;5618.887622247614;5621.269234999424;5718.342390625131;5720.628159169641;5788.160716696139 22:20:04 INFO - PROCESS | 1639 | |1;ext.html;500.0199608766817;509.5974180397486;520.976281606643;532.0104605433894;555.77768892443;37021.81489092554;39526;39644.810655680376;39782.60217397829;39806;40362.394564081485;41193.79403102988;41250;41300.79349603248;41435.17129657405;9203.953980230106;11038.282511636758;11414.54341826326;11419.828702569468;11570.884291157106;11115.666530004113;11181.273217240854;11188.049015833663;11203.719907002309;11238.100951923827;11229;11248.831267530994;11249.10007199422;11255.324680519154;11256.324620522795;11072.446377681084;11141.665750027512;11142.331460112418;11145.888541114648;11166.609168679139;11058.11535077192;11097.00126988575;11148.665540033811;11149;11157.553697852076;29205.85397073001;35402.645973540195;35443.4683479748;35569.644303556895;35630;3734.271816995687;3742.7380083394173;3752.6434988676306;3756.098536351256;3756.8121593920196;27083.45833083347;27336.179914602595;27586.448271034667;27745.58381624277;27823.860880695465;2610.425706344601;3052.450558899397;3075.261937135094;3089.413011527825;3101.829399383033;438354;439980;440480;441811;443181;44248;45292.77353613211;52456.737716311174;53356.46643533515;53512.46487535153;50554.23060192876;55696.16455753167;56025.71987140038;56161.87676246534;56315.15527267094;12349.691257718629;13253.469861205636;14225.359858806378;14259.358328875018;14285.857141428452;10930.781384372427;10970.451477426257;10987.175961802897;11112.499937502831;11121.27711698737;16791.748123778154;16965.830341696426;17037.7444338335;17047.91476042612;17091.401800937158;6208.782692605737;6766.560173588799;7098.39663628587;7552.66013029404;7611.581363025087;712.8146681862753;728.726727477196;827.962741676626;843.5191940593803;854.6026097864523;20968.89515552443;22495.550088998127;27942.44115117727;28757.424851502852;29341.413171736873;990.8910019897805;1025.3437799808128;1036.362636978253;1054.146141625286;1157.6006277834133;13573.932130339483;14753.114813111248;14960.40158393652;14993.775093373608;15498.526632794894;571845;573993;574731;575318.1234093803;575518;57081.714591427604;57332.71333643305;57679.8464030717;57693.13460298099;57802.843943120904;1322.7090040191142;1367.1660287224786;1372.1218420211073;1375.7592421326228;1385.889128869688;27569.310767230978;27939.301517462227;28822.279443014093;28936.55317234088;29101.12696619105;47362.52637473581;47767;47787.76106119586;47940.7602961983;48073.03853922832;475547;478720;479535;480182;480553;24013.51972960566;24348.147814826396;24446.144384947154;24523.01907923663;24552.87723561371;44314.77842610766;44804;45636.315455268865;45814.31278530758;46102.769486152356;36506.634933650326;36614.08464788402;37165.25669486541;37698.81150594339;37775;34411.31177376388;36108.81945590255;36109.45835812517;37472.625273748;37487;35587;35809.64190358063;36669;36797;37205.44191837179;31112.755489780156;31195;32819.50770738489;32827.01518954435;32855.342893141526;21824.908754562526;22554.774452255268;23609.055637774298;23803.64294535549;24829 22:20:04 INFO - PROCESS | 1639 | |2;jquery.html;382.50465646987146;385.57779231741245;385.88423472958107;394.1525719702638;424.1834468647852;13627.659308517292;19256.326028588985;20432.489187770312;20685.586288274226;20772.89613551932;20641.483962900937;20876.791232087675;21863.78136218637;22003.669944950838;22100.447488812748;5114.130597798374;5450.672959622435;5453.23654688344;5496.835094947138;5503.064479038573;4664.603508701764;5245.711485868275;5451.100568406208;5456.8090116845915;5464.890702185954;4640.767961601923;5433.429489903562;5459.099248623972;5473.261109750192;5482.862928426782;5422.457754224566;5454.863628409282;5456.7544460499175;5461.317335333083;5655.434456554352;5757.712114394305;5879.176915231871;5896.292444906596;5900.350961394243;5911;13142.4085916134;13363.265020423873;13409.731805363936;13536.59390218295;13776.8289695375;2331.2073894875825;2359.893804778772;2514.497100579891;2557.2711777143427;2583.7803786678155;9621.139573856804;9738.805223895482;10004.449755263458;10016.89632789783;10172.94913525435;1759.129231030648;1766.2581715679316;1781.1361489677497;1807.5842556212012;1809.1135343681633;119938;124336;124984;126323.36838315842;129822.3508882459;11593.536258549564;11606.839316068494;12118.878811211775;12121.454534545966;12139.393030348447;13305;13893.58319250424;15817.920910395604;15854.52436426886;15878.841211587967;6860.588364698134;8067.596620168968;8401.369897257608;8408.2432581068;8529.616167272488;7958.124606293303;7964.8805267921025;8152.144024877421;8196.754097377088;8227.8354432911;4137.793110344531;4802.7838747255755;4849.563539281482;4859.402679707619;4937.1113199624415;2033.94915127123;2063.957701360833;2067.648499755045;2070.995567149931;2080.9687854682193;994.5524513968743;1006.9748256293651;1008.8991100889999;1023.3143793658267;1053.4311471805293;17872;19650.70523942142;20786.792132078786;20869.852158131143;21341.573168536852;1018.9032041956002;1023.9283250172565;1050.1703654113164;1054.894510548939;1062.4528367890543;9644.710658680251;10345.534450949724;11059.502322395512;11139.443027848574;11147.108231341474;642108;668346;685244;712910;720481;139478;143282.28358858346;144143;144161;145497;996.0786272697726;1008.7528555503949;1022.7931041371253;1023.125227930124;1030.283952652899;17590.73613895767;17718.84827486209;18132.546686332418;18184.636307273515;18601.53496162607;53926;54981;55183;56260.15609765939;56717;77959.44081118233;79050;81123.18876811156;81453.59273203833;88840.11159888576;6784.457243420416;7368.852622947404;7411.925880741339;7499.2500749924575;7661;11148.996590306911;12896.871031289567;12977.870221297666;13256.07207495466;13436.462541498231;11062.22564420483;11545.191836571359;11687.474063666985;11702.765944681229;11744.471498782405;9371.203447707021;11103.723071846518;11262.605808796652;11471.655850324503;11641.06871450299;11407.885921140682;11598.536058557564;11764;11768.470418831344;11874.881251187378;7401.33387995083;7549.131849837339;7561.281678240558;7730.072391313079;7749.675097470849;10841.728956776144;10994.175436842428;11426.94286528562;11520.596779112691;11632.859128035552 22:20:04 INFO - PROCESS | 1639 | |3;mootools.html;1425.6293363725429;1433.0255426310111;1445.9421623135074;1453.3242042450263;1466.0983495150485;6802.251752307242;8227.91772082279;8371.539565323905;8627.698030568923;8678.913210867897;6808.046873437723;7208.747693830717;8409.621567029482;8547.914520854789;8561.357898157632;1889.9527511812178;1922.9134688938962;1952;2302.481941563152;2304.1704986204954;2265.399669087693;2303.5738388398117;2307.469282065126;2309.2033248529315;2317.316391664463;2257.0181970842655;2259.819214462839;2287.501686395405;2290.3243543154726;2300.884955752214;1875.2780179630804;1879.793222745497;2260.9660855087186;2263.852849564773;2268.0814270220585;1910.455520176743;1945.2802463088606;1981.791911849264;2209.1826024370926;2217.81148602369;8500.617472213766;8608.31133509324;8663.95668021656;8667.609957551926;8677.956610216972;5107.719075450848;5250.18622113573;5251.317328747238;5251.396089449724;5260.973695131538;3422.6748458896363;3438.5186073949417;3469.410200265961;3504.6530563311976;3511.4206155984234;1186.3237954366016;1200.5317926008863;1221.4564518789143;1248.182440501473;1252.0734656354323;10841.078508326802;10852.240343175981;10884.891151088466;10952.069074128789;10992.560297588087;4166.583341665809;4460.687751857404;4519.864404067883;4529.365888775574;4531.728096314232;8747.518886461306;8763.036066032731;8820.647174113092;8832.690855820016;8863.379563430499;5052.671576347526;5110.310108135429;5110.4123025852505;5115.846524604268;5121.487851214849;4611.700239484489;4612.377329060601;4667.043256132485;4754.548317909793;4772.952270477251;3647.434647629622;3678.3746763050344;3711.0907827581877;3729.310077635646;3776.263628592398;1141.7830612183825;1142.3146112332533;1146.4439746722833;1155.936423496699;1157.5080590749067;849.1211595998112;871.250724377048;882.2853488674194;913.8035322405715;914.1178762494225;5121.8719532012;5227.738613069331;5433.483819037264;5707.800226992035;5925.644461332247;784.9372050235968;966.3815158298695;984.084801134937;992.4343124419012;996.5216695985968;3375;3504.3341765064556;3711.498947642088;3731.2164445466215;3911.3546264865977;8403.747887563382;9674.951625241829;9930.553125109387;10002.249831262685;10145.797084058426;9427.481488518197;10035.548400322003;10054.044865737744;10067.395956242648;10167;6634.701438435282;7496.437767167485;7752.496087754278;7896.289333959856;7973.641186146638;5117.718525481134;5542.667439953616;5671.914921276101;5827.737751801094;5834.766609335579;8872.112788721077;9037.141471560191;9067.546622668708;9214.401063930824;9285.4892980884;9296.256299495888;9413.058694130532;9491;9562.569684364082;9579.473128977694;7262.818429539304;7378.778636640909;7379.3358597726465;7383.778486645409;7416.517926334771;7702.037245344332;7920.485168464032;7923.20767923203;7931.682732690859;7989.720359787612;7108.324709152622;7135.108111486064;7186.10173728284;7191.892121618284;7282.635868206675;6985.8253543659785;7005.194402643558;7071.7878463646175;7119.181294151239;7129.536580122275;6363.300036996019;6373.681315934277;6382.20222472191;6397.488200944006;6441.5490915635455;4344.283193273074;5003.54968052877;5021.297018417497;5086.262491938722;5147;4476;4807.7836497357;4834.105690447279;5021.4225364083595;5168.302279192261 22:20:04 INFO - PROCESS | 1639 | |4;prototype.html;285.6700510909897;309.4955222986532;353.45391370332834;371.0815731065612;379.6412390291174;3805.733598648089;4392.758398288101;4867.833813952788;5395.9460405395985;5414.566834653233;4099.50805903292;4771.807048237941;5196.974015129919;5219.243209734595;5253.474652534745;3498.440249560077;3556.413191823346;3598.694111000555;4089.8159582818844;4132.648724858391;3312.817795021273;3792.886213413602;4137.213929353413;4149.7095203335775;4158.8960275993195;3732.9253414931686;3933.567307596162;3959.4456776051375;3965.484487016684;3984.9278375244853;3604.7290207169576;3769.9821048317076;3927.4894263745673;3964.801759912007;4022.3765316376007;3036.9088927332214;3337.599488061424;3479.9908026672233;3484.494748261488;3489.4068008438626;4217.325227963511;4779.617630589544;4817.85546433604;5018.372703412074;5217.947820521784;3664.926701465982;3981.28336899358;4516.5998540452565;4551.249043907751;4562.315652652095;4323.913521729548;5522.530584900288;5522.779088836482;5540.861478463071;5585.8603534911545;3091.9536206956914;3232.531282963957;3401.0817079388426;3820.083180036771;3822.445745366934;5440.782368705247;5678.261825962599;5727.427257274239;5796.536277097822;5801.7389217485315;3212.9196770080935;3763.513412202165;3820.7134464915243;3842.6358642682003;3869.903252418712;3475.913102172415;3881.650651441327;3898.0060084678794;3929.8231579578987;3929.980350098231;3853.807309634507;4013.8795836124964;4245.002424430257;4442.089371678798;4587.807170135722;4787.904241915212;4890.657653964188;5433;5632.802851900164;5659.773609055674;1104.7737011916765;1117.9776404471866;1162.7906976744098;1254.5985284708806;1275.9872401276054;711.5019486359569;787.6180052674448;842.8862103615934;858.1804376820122;867.2498288979987;4258.127083947783;4459.955400445954;4947.2579113133315;4948.455669876311;5027.421846487628;5171.2501687255135;5481.506664400223;5484.61607687458;5498.670079795225;5514.696691681916;4180.665546756313;4488.912655615155;4843.425886586873;4998.300237966688;5085.64400491962;2837.333226691767;2839.957400638992;2862.9284267893468;2892.5661150827123;2905.273681579605;5269.841904742864;5370.516653501204;5610.381304058859;5699.658020518699;5758.884822303614;4494.213512635341;4860.513948605182;5163.320347617666;5296.735163241822;5622.465865742748;5541.722913854291;5709.486146246858;6004.909926351108;6020.638761674313;6041.546883983631;4655.4413470382415;5325.653832500875;5793.94206057934;5822.475977162076;5868.23712917318;5243.8164664236565;5277;5546.92145859036;5713.885722285448;5719.399463056319;5650.886982260414;5838.263511352822;5976.372480889444;5999.370066142992;6000.579959402975;3803.904902377463;3861.8262178201485;3885.2220662226646;3895.9220815584094;3925.2542017016676;5345.973270133624;5666.886662266649;5751.913721294268;5770.740316685676;5795.420457954339;4449.7775111244955;5273.709945953009;5477.890442191214;5531.640443371168;5625.7806645016235;5171.534561889448;5524.447555244444;5568.1091025435735;5587;5607;4701.717896926195;5389.811356602657;5437.673739575638;5480.890382192254;5496.972515137399;4276.444062272009;4939.43196532389;5077.568406685473;5565.833025009256;5567.220589117445;4751.61987041029;5016.971520838147;5504.80733174337;5523.25436066142;5539.8892022160135 22:20:04 INFO - PROCESS | 1639 | |5;yui.html;670.8960111182765;678.3453966921919;761.0866959648423;795.3994733975848;801.0547553886413;14493.130412175276;15261.160636165007;15428.074315541075;15472.226388680578;15493.302801373922;15134.924325378357;16185.19074046296;16186.433474828384;16535.83464165361;16551.751723724155;883.5891310540597;907.9682211122612;920.6639576554543;926.5737760630118;930.0188301342081;905.2757793764995;927.1053433436732;928.5774972387547;929.8605209218604;930.4882314726873;918.9908100919006;975.5366201054513;982.6806287956442;985.3693636072919;988.7972965542083;968.9321747477679;971.820213260549;972.5817898303727;973.3770386952356;973.8490533967175;649.5712829532533;687.3092541995263;725.793148952546;725.8911163325544;730.215018854728;15323.846761532353;15378.231088445646;15471.767923481044;15583.376664933388;15674.9216253918;13876.722465550733;14084.366203520764;14132.434702611883;14168.079074860101;14233.501827436094;10769;10811.945940270249;10899.673009809718;11067.446627668583;11292.209545331747;3633.5458067741533;3657.65252301031;3668.4313931340685;3760.793156376398;3797.9240415191816;85042.14957850405;90864.54567727185;99527.99999999927;99539.50230248875;99603.50198248963;10418.843717344094;10689.412082335391;10865.34807911512;10950.780984380268;11189.49647265875;21751;21931.01310441034;22245.777542224696;22323.776762232494;22336.106555737588;7611.885821712679;7717.8456430871065;7742.61286935651;7818.14000459949;7954.960225198837;4228.513720922072;4262.893427664271;4353.390525326457;4421.53573874745;4431.889202769957;3124.7968882022597;3631.2374401376005;3685.4287585423767;3735.5517337919623;3740.9812950935616;589.4429763873098;612.702839123025;627.1909237084202;665.8568407792347;695.3359541637724;630.4105661206786;642.6401215319436;669.8445182061004;679.942204912588;700.9088818453569;9381.530923453936;9739.707808765748;9830.115289623825;9874.111329980336;9975.750606234757;655.6426747422571;687.3023880761032;688.8828899087067;693.3423266148307;717.5156769180782;4440.7335559866515;4670.346151538719;4708.976455117702;4819.1808989549045;4892.608591312686;90971.54514227387;92445;97586;97787;99011.00988990162;72539;85321.14678853132;86692;86810.56594716986;87306.12693873107;74701.6264918672;76352;76685;76811.46377072427;78186.99999999886;9005.324600654845;9178.787067606778;9201.539923003957;9286.30352723535;9420.293477989042;42406.151876961674;48287.75856120697;49325.75337123292;50597.24104138515;51979.74010129925;50684.49315506945;59071;65793;66035;66606;12659.936700316439;12768.489260429478;12884.162529435556;13056.608301750963;13168.47326106945;14230.857691422952;14263.215523146326;14269.857301427268;14526.20105894186;14563.708725825636;13190.736185276433;13236.470541178245;13380.732385352434;13484.325783710972;13848.861511384757;13074.150180238254;13203.075784695364;13383.464661413434;13822.30888455553;13840.446382145004;12712.74574508523;12910.031747618777;13059.542915997894;13767.931160344135;13802;8701.521416322163;10707.732306692084;10773.461326933779;10883.78232435331;11449.198556100993;8613.82772344537;9102.362834601514;9585.760355991157;9771.609135634495;10716.196285278791 22:20:04 INFO - PROCESS | 1639 | __end_tp_report 22:20:04 INFO - PROCESS | 1639 | __start_cc_report 22:20:04 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,418 22:20:04 INFO - PROCESS | 1639 | __end_cc_report 22:20:04 INFO - PROCESS | 1639 | __startTimestamp1488867604385__endTimestamp 22:20:04 INFO - PROCESS | 1639 | 22:20:04 INFO - PROCESS | 1639 | ------- Summary: start ------- 22:20:04 INFO - PROCESS | 1639 | Number of tests: 6 22:20:04 INFO - PROCESS | 1639 | 22:20:04 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5580.62 Median:5757.88 stddev:638.93 (11.1%) stddev-sans-first:611.20 22:20:04 INFO - PROCESS | 1639 | Values: 3126.5 3317.5 3336.6 3383.6 3402.9 5450.2 5553.8 5605.0 5611.1 5653.8 4514.4 5470.4 5512.1 5554.8 5563.2 4978.7 5071.2 5120.5 5150.4 5156.4 4976.1 5154.6 5217.0 5238.0 5374.9 5003.9 5072.5 5092.7 5159.6 5200.6 5135.9 5137.2 5141.7 5165.1 5170.2 5099.0 5103.3 5120.6 5137.3 5161.5 5279.4 5451.7 5454.7 5524.3 5600.4 6131.3 6165.4 6166.1 6166.6 6209.3 6186.9 6191.5 6197.8 6198.2 6216.8 5555.1 5629.9 5747.6 5762.3 5790.9 6147.3 6212.1 6216.0 6218.8 6223.4 5757.9 5848.0 6049.8 6050.5 6077.2 6039.0 6104.8 6106.6 6137.2 6147.4 6173.9 6176.2 6181.1 6183.8 6211.6 5930.8 5998.0 6003.1 6034.5 6054.6 4842.0 4856.8 4870.2 4886.7 4943.0 4363.0 4368.7 4387.9 4393.1 4427.3 4667.9 4748.4 5684.5 5685.4 5702.8 4983.6 5098.7 5128.8 5310.9 6191.1 5732.4 5735.5 5767.6 5795.1 5799.2 4241.9 5295.1 5408.7 5432.1 5480.6 5119.7 6110.3 6135.8 6158.0 6248.9 5064.4 6103.8 6122.7 6125.5 6153.2 6163.3 6185.0 6189.8 6191.3 6194.8 5885.8 5899.4 5948.6 5996.6 6035.2 6102.4 6121.8 6137.0 6144.2 6255.0 6017.9 6037.6 6052.5 6068.5 6072.0 5776.3 5836.3 5883.6 5921.9 5965.2 5659.5 5733.7 5762.5 5762.6 5770.5 5902.6 5926.6 5934.2 5947.8 5976.9 5618.9 5621.3 5718.3 5720.6 5788.2 22:20:04 INFO - PROCESS | 1639 | 22:20:04 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:62917.80 Median:35423.06 stddev:133543.55 (377.0%) stddev-sans-first:133835.94 22:20:04 INFO - PROCESS | 1639 | Values: 500.0 509.6 521.0 532.0 555.8 37021.8 39526.0 39644.8 39782.6 39806.0 40362.4 41193.8 41250.0 41300.8 41435.2 9204.0 11038.3 11414.5 11419.8 11570.9 11115.7 11181.3 11188.0 11203.7 11238.1 11229.0 11248.8 11249.1 11255.3 11256.3 11072.4 11141.7 11142.3 11145.9 11166.6 11058.1 11097.0 11148.7 11149.0 11157.6 29205.9 35402.6 35443.5 35569.6 35630.0 3734.3 3742.7 3752.6 3756.1 3756.8 27083.5 27336.2 27586.4 27745.6 27823.9 2610.4 3052.5 3075.3 3089.4 3101.8 438354.0 439980.0 440480.0 441811.0 443181.0 44248.0 45292.8 52456.7 53356.5 53512.5 50554.2 55696.2 56025.7 56161.9 56315.2 12349.7 13253.5 14225.4 14259.4 14285.9 10930.8 10970.5 10987.2 11112.5 11121.3 16791.7 16965.8 17037.7 17047.9 17091.4 6208.8 6766.6 7098.4 7552.7 7611.6 712.8 728.7 828.0 843.5 854.6 20968.9 22495.6 27942.4 28757.4 29341.4 990.9 1025.3 1036.4 1054.1 1157.6 13573.9 14753.1 14960.4 14993.8 15498.5 571845.0 573993.0 574731.0 575318.1 575518.0 57081.7 57332.7 57679.8 57693.1 57802.8 1322.7 1367.2 1372.1 1375.8 1385.9 27569.3 27939.3 28822.3 28936.6 29101.1 47362.5 47767.0 47787.8 47940.8 48073.0 475547.0 478720.0 479535.0 480182.0 480553.0 24013.5 24348.1 24446.1 24523.0 24552.9 44314.8 44804.0 45636.3 45814.3 46102.8 36506.6 36614.1 37165.3 37698.8 37775.0 34411.3 36108.8 36109.5 37472.6 37487.0 35587.0 35809.6 36669.0 36797.0 37205.4 31112.8 31195.0 32819.5 32827.0 32855.3 21824.9 22554.8 23609.1 23803.6 24829.0 22:20:04 INFO - PROCESS | 1639 | 22:20:04 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:37831.89 Median:20779.84 stddev:114462.75 (550.8%) stddev-sans-first:114749.31 22:20:04 INFO - PROCESS | 1639 | Values: 382.5 385.6 385.9 394.2 424.2 13627.7 19256.3 20432.5 20685.6 20772.9 20641.5 20876.8 21863.8 22003.7 22100.4 5114.1 5450.7 5453.2 5496.8 5503.1 4664.6 5245.7 5451.1 5456.8 5464.9 4640.8 5433.4 5459.1 5473.3 5482.9 5422.5 5454.9 5456.8 5461.3 5655.4 5757.7 5879.2 5896.3 5900.4 5911.0 13142.4 13363.3 13409.7 13536.6 13776.8 2331.2 2359.9 2514.5 2557.3 2583.8 9621.1 9738.8 10004.4 10016.9 10172.9 1759.1 1766.3 1781.1 1807.6 1809.1 119938.0 124336.0 124984.0 126323.4 129822.4 11593.5 11606.8 12118.9 12121.5 12139.4 13305.0 13893.6 15817.9 15854.5 15878.8 6860.6 8067.6 8401.4 8408.2 8529.6 7958.1 7964.9 8152.1 8196.8 8227.8 4137.8 4802.8 4849.6 4859.4 4937.1 2033.9 2064.0 2067.6 2071.0 2081.0 994.6 1007.0 1008.9 1023.3 1053.4 17872.0 19650.7 20786.8 20869.9 21341.6 1018.9 1023.9 1050.2 1054.9 1062.5 9644.7 10345.5 11059.5 11139.4 11147.1 642108.0 668346.0 685244.0 712910.0 720481.0 139478.0 143282.3 144143.0 144161.0 145497.0 996.1 1008.8 1022.8 1023.1 1030.3 17590.7 17718.8 18132.5 18184.6 18601.5 53926.0 54981.0 55183.0 56260.2 56717.0 77959.4 79050.0 81123.2 81453.6 88840.1 6784.5 7368.9 7411.9 7499.3 7661.0 11149.0 12896.9 12977.9 13256.1 13436.5 11062.2 11545.2 11687.5 11702.8 11744.5 9371.2 11103.7 11262.6 11471.7 11641.1 11407.9 11598.5 11764.0 11768.5 11874.9 7401.3 7549.1 7561.3 7730.1 7749.7 10841.7 10994.2 11426.9 11520.6 11632.9 22:20:04 INFO - PROCESS | 1639 | 22:20:04 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:5377.07 Median:5121.68 stddev:2963.41 (57.9%) stddev-sans-first:2956.85 22:20:04 INFO - PROCESS | 1639 | Values: 1425.6 1433.0 1445.9 1453.3 1466.1 6802.3 8227.9 8371.5 8627.7 8678.9 6808.0 7208.7 8409.6 8547.9 8561.4 1890.0 1922.9 1952.0 2302.5 2304.2 2265.4 2303.6 2307.5 2309.2 2317.3 2257.0 2259.8 2287.5 2290.3 2300.9 1875.3 1879.8 2261.0 2263.9 2268.1 1910.5 1945.3 1981.8 2209.2 2217.8 8500.6 8608.3 8664.0 8667.6 8678.0 5107.7 5250.2 5251.3 5251.4 5261.0 3422.7 3438.5 3469.4 3504.7 3511.4 1186.3 1200.5 1221.5 1248.2 1252.1 10841.1 10852.2 10884.9 10952.1 10992.6 4166.6 4460.7 4519.9 4529.4 4531.7 8747.5 8763.0 8820.6 8832.7 8863.4 5052.7 5110.3 5110.4 5115.8 5121.5 4611.7 4612.4 4667.0 4754.5 4773.0 3647.4 3678.4 3711.1 3729.3 3776.3 1141.8 1142.3 1146.4 1155.9 1157.5 849.1 871.3 882.3 913.8 914.1 5121.9 5227.7 5433.5 5707.8 5925.6 784.9 966.4 984.1 992.4 996.5 3375.0 3504.3 3711.5 3731.2 3911.4 8403.7 9675.0 9930.6 10002.2 10145.8 9427.5 10035.5 10054.0 10067.4 10167.0 6634.7 7496.4 7752.5 7896.3 7973.6 5117.7 5542.7 5671.9 5827.7 5834.8 8872.1 9037.1 9067.5 9214.4 9285.5 9296.3 9413.1 9491.0 9562.6 9579.5 7262.8 7378.8 7379.3 7383.8 7416.5 7702.0 7920.5 7923.2 7931.7 7989.7 7108.3 7135.1 7186.1 7191.9 7282.6 6985.8 7005.2 7071.8 7119.2 7129.5 6363.3 6373.7 6382.2 6397.5 6441.5 4344.3 5003.5 5021.3 5086.3 5147.0 4476.0 4807.8 4834.1 5021.4 5168.3 22:20:04 INFO - PROCESS | 1639 | 22:20:04 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4402.47 Median:4864.17 stddev:1384.42 (28.5%) stddev-sans-first:1353.38 22:20:04 INFO - PROCESS | 1639 | Values: 285.7 309.5 353.5 371.1 379.6 3805.7 4392.8 4867.8 5395.9 5414.6 4099.5 4771.8 5197.0 5219.2 5253.5 3498.4 3556.4 3598.7 4089.8 4132.6 3312.8 3792.9 4137.2 4149.7 4158.9 3732.9 3933.6 3959.4 3965.5 3984.9 3604.7 3770.0 3927.5 3964.8 4022.4 3036.9 3337.6 3480.0 3484.5 3489.4 4217.3 4779.6 4817.9 5018.4 5217.9 3664.9 3981.3 4516.6 4551.2 4562.3 4323.9 5522.5 5522.8 5540.9 5585.9 3092.0 3232.5 3401.1 3820.1 3822.4 5440.8 5678.3 5727.4 5796.5 5801.7 3212.9 3763.5 3820.7 3842.6 3869.9 3475.9 3881.7 3898.0 3929.8 3930.0 3853.8 4013.9 4245.0 4442.1 4587.8 4787.9 4890.7 5433.0 5632.8 5659.8 1104.8 1118.0 1162.8 1254.6 1276.0 711.5 787.6 842.9 858.2 867.2 4258.1 4460.0 4947.3 4948.5 5027.4 5171.3 5481.5 5484.6 5498.7 5514.7 4180.7 4488.9 4843.4 4998.3 5085.6 2837.3 2840.0 2862.9 2892.6 2905.3 5269.8 5370.5 5610.4 5699.7 5758.9 4494.2 4860.5 5163.3 5296.7 5622.5 5541.7 5709.5 6004.9 6020.6 6041.5 4655.4 5325.7 5793.9 5822.5 5868.2 5243.8 5277.0 5546.9 5713.9 5719.4 5650.9 5838.3 5976.4 5999.4 6000.6 3803.9 3861.8 3885.2 3895.9 3925.3 5346.0 5666.9 5751.9 5770.7 5795.4 4449.8 5273.7 5477.9 5531.6 5625.8 5171.5 5524.4 5568.1 5587.0 5607.0 4701.7 5389.8 5437.7 5480.9 5497.0 4276.4 4939.4 5077.6 5565.8 5567.2 4751.6 5017.0 5504.8 5523.3 5539.9 22:20:04 INFO - PROCESS | 1639 | 22:20:04 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:19542.70 Median:26284.59 stddev:27273.19 (103.8%) stddev-sans-first:27312.89 22:20:04 INFO - PROCESS | 1639 | Values: 670.9 678.3 761.1 795.4 801.1 14493.1 15261.2 15428.1 15472.2 15493.3 15134.9 16185.2 16186.4 16535.8 16551.8 883.6 908.0 920.7 926.6 930.0 905.3 927.1 928.6 929.9 930.5 919.0 975.5 982.7 985.4 988.8 968.9 971.8 972.6 973.4 973.8 649.6 687.3 725.8 725.9 730.2 15323.8 15378.2 15471.8 15583.4 15674.9 13876.7 14084.4 14132.4 14168.1 14233.5 10769.0 10811.9 10899.7 11067.4 11292.2 3633.5 3657.7 3668.4 3760.8 3797.9 85042.1 90864.5 99528.0 99539.5 99603.5 10418.8 10689.4 10865.3 10950.8 11189.5 21751.0 21931.0 22245.8 22323.8 22336.1 7611.9 7717.8 7742.6 7818.1 7955.0 4228.5 4262.9 4353.4 4421.5 4431.9 3124.8 3631.2 3685.4 3735.6 3741.0 589.4 612.7 627.2 665.9 695.3 630.4 642.6 669.8 679.9 700.9 9381.5 9739.7 9830.1 9874.1 9975.8 655.6 687.3 688.9 693.3 717.5 4440.7 4670.3 4709.0 4819.2 4892.6 90971.5 92445.0 97586.0 97787.0 99011.0 72539.0 85321.1 86692.0 86810.6 87306.1 74701.6 76352.0 76685.0 76811.5 78187.0 9005.3 9178.8 9201.5 9286.3 9420.3 42406.2 48287.8 49325.8 50597.2 51979.7 50684.5 59071.0 65793.0 66035.0 66606.0 12659.9 12768.5 12884.2 13056.6 13168.5 14230.9 14263.2 14269.9 14526.2 14563.7 13190.7 13236.5 13380.7 13484.3 13848.9 13074.2 13203.1 13383.5 13822.3 13840.4 12712.7 12910.0 13059.5 13767.9 13802.0 8701.5 10707.7 10773.5 10883.8 11449.2 8613.8 9102.4 9585.8 9771.6 10716.2 22:20:04 INFO - PROCESS | 1639 | -------- Summary: end -------- 22:20:04 INFO - PROCESS | 1639 | 22:20:05 INFO - TEST-INFO | 1639: exit 0 22:20:05 INFO - TEST-OK | dromaeo_css | took 1116856ms 22:20:05 INFO - TEST-START | kraken 22:20:05 INFO - operating with platform_type : mac_ 22:20:05 INFO - Initialising browser for kraken test... 22:20:05 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/tmpIvpjFg/profile http://localhost:49238/getInfo.html) 22:20:07 INFO - PROCESS | 1647 | 2017-03-06 22:20:07.738 plugin-container[1648:10074] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d4f, name = 'com.apple.tsm.portname' 22:20:07 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:20:07 INFO - PROCESS | 1647 | 2017-03-06 22:20:07.741 plugin-container[1648:10074] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910f, name = 'com.apple.CFPasteboardClient' 22:20:07 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:20:07 INFO - PROCESS | 1647 | 2017-03-06 22:20:07.741 plugin-container[1648:10074] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:20:07 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 22:20:07 INFO - PROCESS | 1647 | colorDepth:24 22:20:07 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 22:20:07 INFO - PROCESS | 1647 | __metrics 22:20:07 ERROR - PROCESS | 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 22:20:08 INFO - TEST-INFO | 1647: exit 0 22:20:08 INFO - Browser initialized. 22:20:08 INFO - Running cycle 1/1 for kraken test... 22:20:08 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/T/tmpIvpjFg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:20:10 INFO - PROCESS | 1649 | 2017-03-06 22:20:10.958 plugin-container[1650:10192] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443f, name = 'com.apple.tsm.portname' 22:20:10 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:20:10 INFO - PROCESS | 1649 | 2017-03-06 22:20:10.959 plugin-container[1650:10192] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 22:20:10 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:20:10 INFO - PROCESS | 1649 | 2017-03-06 22:20:10.960 plugin-container[1650:10192] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:20:20 INFO - PROCESS | 1649 | RSS: Main: 233623552 22:20:20 INFO - PROCESS | 1649 | 22:20:23 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-beat-detection) 22:20:24 INFO - PROCESS | 1649 | RSS: Main: 247447552 22:20:24 INFO - PROCESS | 1649 | 22:20:27 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-dft) 22:20:27 INFO - PROCESS | 1649 | RSS: Main: 255459328 22:20:27 INFO - PROCESS | 1649 | 22:20:31 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-fft) 22:20:32 INFO - PROCESS | 1649 | RSS: Main: 258158592 22:20:32 INFO - PROCESS | 1649 | 22:20:34 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-oscillator) 22:20:34 INFO - PROCESS | 1649 | RSS: Main: 259313664 22:20:34 INFO - PROCESS | 1649 | 22:20:36 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:20:37 INFO - PROCESS | 1649 | RSS: Main: 261017600 22:20:37 INFO - PROCESS | 1649 | 22:20:40 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-darkroom) 22:20:41 INFO - PROCESS | 1649 | RSS: Main: 266878976 22:20:41 INFO - PROCESS | 1649 | 22:20:44 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-desaturate) 22:20:45 INFO - PROCESS | 1649 | RSS: Main: 266031104 22:20:45 INFO - PROCESS | 1649 | 22:20:48 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49238/tests/kraken/driver.html?testName=json-parse-financial) 22:20:48 INFO - PROCESS | 1649 | RSS: Main: 265867264 22:20:48 INFO - PROCESS | 1649 | 22:20:50 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49238/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:20:51 INFO - PROCESS | 1649 | RSS: Main: 265449472 22:20:51 INFO - PROCESS | 1649 | 22:20:52 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:20:53 INFO - PROCESS | 1649 | RSS: Main: 265482240 22:20:53 INFO - PROCESS | 1649 | 22:20:55 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:20:56 INFO - PROCESS | 1649 | RSS: Main: 265486336 22:20:56 INFO - PROCESS | 1649 | 22:20:59 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:21:00 INFO - PROCESS | 1649 | RSS: Main: 266043392 22:21:00 INFO - PROCESS | 1649 | 22:21:03 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:21:04 INFO - PROCESS | 1649 | RSS: Main: 265928704 22:21:04 INFO - PROCESS | 1649 | 22:21:05 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49238/tests/kraken/driver.html?testName=ai-astar) 22:21:06 INFO - PROCESS | 1649 | RSS: Main: 268963840 22:21:06 INFO - PROCESS | 1649 | 22:21:06 INFO - PROCESS | 1649 | __start_tp_report 22:21:06 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 22:21:06 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 22:21:06 INFO - PROCESS | 1649 | |i|pagename|runs| 22:21:06 INFO - PROCESS | 1649 | |0;ai-astar;106;118;118;118;117;118;117;132;173;156 22:21:06 INFO - PROCESS | 1649 | |1;audio-beat-detection;141;115;114;155;117;119;117;114;137;116 22:21:06 INFO - PROCESS | 1649 | |2;audio-dft;203;205;229;240;211;287;253;199;193;199 22:21:06 INFO - PROCESS | 1649 | |3;audio-fft;75;76;75;111;85;85;64;64;74;62 22:21:06 INFO - PROCESS | 1649 | |4;audio-oscillator;89;71;89;70;74;71;82;103;99;116 22:21:06 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;89;89;85;87;90;136;108;89;88;89 22:21:06 INFO - PROCESS | 1649 | |6;imaging-darkroom;100;156;121;106;95;153;154;104;123;107 22:21:06 INFO - PROCESS | 1649 | |7;imaging-desaturate;93;95;91;104;144;121;94;94;93;95 22:21:06 INFO - PROCESS | 1649 | |8;json-parse-financial;63;63;63;66;65;64;65;65;64;65 22:21:06 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;74;69;75;76;73;73;70;70;69;72 22:21:06 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;91;92;93;92;93;110;82;100;120;108 22:21:06 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;144;152;145;146;144;162;163;159;210;160 22:21:06 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;202;200;175;229;178;174;243;165;239;174 22:21:06 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;71;68;67;72;70;62;85;87;89;71 22:21:06 INFO - PROCESS | 1649 | __end_tp_report 22:21:06 INFO - PROCESS | 1649 | __start_cc_report 22:21:06 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1004 22:21:06 INFO - PROCESS | 1649 | __end_cc_report 22:21:06 INFO - PROCESS | 1649 | __startTimestamp1488867666221__endTimestamp 22:21:06 INFO - PROCESS | 1649 | 22:21:06 INFO - PROCESS | 1649 | ------- Summary: start ------- 22:21:06 INFO - PROCESS | 1649 | Number of tests: 14 22:21:06 INFO - PROCESS | 1649 | 22:21:06 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:127.30 Median:118.00 stddev:20.94 (17.7%) stddev-sans-first:20.74 22:21:06 INFO - PROCESS | 1649 | Values: 106.0 118.0 118.0 118.0 117.0 118.0 117.0 132.0 173.0 156.0 22:21:06 INFO - PROCESS | 1649 | 22:21:06 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:124.50 Median:118.00 stddev:14.47 (12.3%) stddev-sans-first:14.06 22:21:06 INFO - PROCESS | 1649 | Values: 141.0 115.0 114.0 155.0 117.0 119.0 117.0 114.0 137.0 116.0 22:21:06 INFO - PROCESS | 1649 | 22:21:06 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:221.90 Median:220.00 stddev:30.24 (13.7%) stddev-sans-first:31.29 22:21:06 INFO - PROCESS | 1649 | Values: 203.0 205.0 229.0 240.0 211.0 287.0 253.0 199.0 193.0 199.0 22:21:06 INFO - PROCESS | 1649 | 22:21:06 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:77.10 Median:75.00 stddev:14.39 (19.2%) stddev-sans-first:15.25 22:21:06 INFO - PROCESS | 1649 | Values: 75.0 76.0 75.0 111.0 85.0 85.0 64.0 64.0 74.0 62.0 22:21:06 INFO - PROCESS | 1649 | 22:21:06 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:86.40 Median:78.00 stddev:15.78 (20.2%) stddev-sans-first:16.71 22:21:06 INFO - PROCESS | 1649 | Values: 89.0 71.0 89.0 70.0 74.0 71.0 82.0 103.0 99.0 116.0 22:21:06 INFO - PROCESS | 1649 | 22:21:06 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:95.00 Median:89.00 stddev:15.75 (17.7%) stddev-sans-first:16.55 22:21:06 INFO - PROCESS | 1649 | Values: 89.0 89.0 85.0 87.0 90.0 136.0 108.0 89.0 88.0 89.0 22:21:06 INFO - PROCESS | 1649 | 22:21:06 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:121.90 Median:138.00 stddev:23.95 (17.4%) stddev-sans-first:24.05 22:21:06 INFO - PROCESS | 1649 | Values: 100.0 156.0 121.0 106.0 95.0 153.0 154.0 104.0 123.0 107.0 22:21:06 INFO - PROCESS | 1649 | 22:21:06 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:102.40 Median:93.50 stddev:17.12 (18.3%) stddev-sans-first:17.81 22:21:06 INFO - PROCESS | 1649 | Values: 93.0 95.0 91.0 104.0 144.0 121.0 94.0 94.0 93.0 95.0 22:21:06 INFO - PROCESS | 1649 | 22:21:06 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:65.00 stddev:1.06 (1.6%) stddev-sans-first:1.01 22:21:06 INFO - PROCESS | 1649 | Values: 63.0 63.0 63.0 66.0 65.0 64.0 65.0 65.0 64.0 65.0 22:21:06 INFO - PROCESS | 1649 | 22:21:06 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:72.10 Median:73.00 stddev:2.51 (3.4%) stddev-sans-first:2.57 22:21:06 INFO - PROCESS | 1649 | Values: 74.0 69.0 75.0 76.0 73.0 73.0 70.0 70.0 69.0 72.0 22:21:06 INFO - PROCESS | 1649 | 22:21:06 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:98.10 Median:91.50 stddev:11.35 (12.4%) stddev-sans-first:11.74 22:21:06 INFO - PROCESS | 1649 | Values: 91.0 92.0 93.0 92.0 93.0 110.0 82.0 100.0 120.0 108.0 22:21:06 INFO - PROCESS | 1649 | 22:21:06 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:158.50 Median:159.50 stddev:19.69 (12.3%) stddev-sans-first:20.17 22:21:06 INFO - PROCESS | 1649 | Values: 144.0 152.0 145.0 146.0 144.0 162.0 163.0 159.0 210.0 160.0 22:21:06 INFO - PROCESS | 1649 | 22:21:06 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:197.90 Median:201.00 stddev:29.55 (14.7%) stddev-sans-first:31.30 22:21:06 INFO - PROCESS | 1649 | Values: 202.0 200.0 175.0 229.0 178.0 174.0 243.0 165.0 239.0 174.0 22:21:06 INFO - PROCESS | 1649 | 22:21:06 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.20 Median:71.50 stddev:9.32 (13.0%) stddev-sans-first:9.81 22:21:06 INFO - PROCESS | 1649 | Values: 71.0 68.0 67.0 72.0 70.0 62.0 85.0 87.0 89.0 71.0 22:21:06 INFO - PROCESS | 1649 | -------- Summary: end -------- 22:21:06 INFO - PROCESS | 1649 | 22:21:07 INFO - TEST-INFO | 1649: exit 0 22:21:07 INFO - TEST-OK | kraken | took 62039ms 22:21:07 INFO - SUITE-END | took 1178s 22:21:07 INFO - Completed test suite (00:19:39) 22:21:07 INFO - javascript benchmark 22:21:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7107.854406669741, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3126.5153901145322, 3317.4692049272107, 3336.6162891267513, 3383.5939687237537, 3402.8638854445817, 5450.209719590657, 5553.7778488860395, 5605.0, 5611.130274807406, 5653.802116925908, 4514.390557274775, 5470.370907345657, 5512.145617429295, 5554.805581804628, 5563.221149039137, 4978.6514943953935, 5071.1886098224295, 5120.4623514530995, 5150.4334523202415, 5156.407013193493, 4976.104301225796, 5154.639175257734, 5217.0, 5238.0, 5374.8925021499745, 5003.949960500385, 5072.51811077947, 5092.745362731848, 5159.613029022819, 5200.635955483118, 5135.897282054375, 5137.229415587654, 5141.665791723563, 5165.096108181091, 5170.1986192140275, 5099.0, 5103.336566246401, 5120.641555091145, 5137.255098010767, 5161.509656582619, 5279.445658205871, 5451.700156491392, 5454.699991500466, 5524.33707955044, 5600.439956004408, 6131.346082861964, 6165.445109940082, 6166.075088736726, 6166.630002199838, 6209.316975132731, 6186.907196392058, 6191.535634827406, 6197.783077592308, 6198.2252218472695, 6216.844578885519, 5555.138953462219, 5629.887402252014, 5747.626404283708, 5762.250907382056, 5790.884182316372, 6147.293061297919, 6212.06818977148, 6215.96892015537, 6218.844528886814, 6223.439890409885, 5757.88484230313, 5848.0350742127075, 6049.788257411057, 6050.45545900871, 6077.179580756541, 6038.969805151034, 6104.7926496150985, 6106.572539922163, 6137.202163718688, 6147.432404736853, 6173.876522469615, 6176.227971503562, 6181.072839074085, 6183.783567575113, 6211.59624624407, 5930.762769489258, 5997.950358687212, 6003.129546215774, 6034.456898879122, 6054.576179667469, 4841.975790121027, 4856.80572777092, 4870.245112007645, 4886.657933944661, 4943.0, 4362.956370436318, 4368.650507959355, 4387.890302742393, 4393.077453734688, 4427.269500532376, 4667.906641867212, 4748.406449193851, 5684.488396044376, 5685.403032681509, 5702.8289151325525, 4983.551480366712, 5098.719570423662, 5128.794848206105, 5310.893782124336, 6191.133241346125, 5732.398098199712, 5735.541156707453, 5767.596268261182, 5795.1017592273265, 5799.246098007232, 4241.851535196253, 5295.099833028395, 5408.702521361284, 5432.076546986943, 5480.561555075585, 5119.718415487184, 6110.2550082019825, 6135.7839197735275, 6157.953147964767, 6248.937510624835, 5064.417591976822, 6103.792276408762, 6122.693865306628, 6125.510168486406, 6153.169322141453, 6163.260408750979, 6185.010398336244, 6189.752409903554, 6191.349908259568, 6194.783182588588, 5885.793997210077, 5899.410058994066, 5948.613340132878, 5996.610220335664, 6035.215421995113, 6102.420270074336, 6121.818815678001, 6137.048757442603, 6144.170536977451, 6254.9687251563455, 6017.909731354121, 6037.611349389577, 6052.546059045501, 6068.544859135671, 6071.969640151771, 5776.277965254343, 5836.299644042742, 5883.61756485827, 5921.940780592311, 5965.164876917148, 5659.54723622101, 5733.655980641175, 5762.510186634183, 5762.567807414544, 5770.451807078321, 5902.6458412495385, 5926.614770039933, 5934.19888315072, 5947.821565353046, 5976.9103463448955, 5618.887622247614, 5621.269234999424, 5718.342390625131, 5720.628159169641, 5788.160716696139], "value": 5541.945787165107, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.0199608766817, 509.5974180397486, 520.976281606643, 532.0104605433894, 555.77768892443, 37021.81489092554, 39526.0, 39644.810655680376, 39782.60217397829, 39806.0, 40362.394564081485, 41193.79403102988, 41250.0, 41300.79349603248, 41435.17129657405, 9203.953980230106, 11038.282511636758, 11414.54341826326, 11419.828702569468, 11570.884291157106, 11115.666530004113, 11181.273217240854, 11188.049015833663, 11203.719907002309, 11238.100951923827, 11229.0, 11248.831267530994, 11249.10007199422, 11255.324680519154, 11256.324620522795, 11072.446377681084, 11141.665750027512, 11142.331460112418, 11145.888541114648, 11166.609168679139, 11058.11535077192, 11097.00126988575, 11148.665540033811, 11149.0, 11157.553697852076, 29205.85397073001, 35402.645973540195, 35443.4683479748, 35569.644303556895, 35630.0, 3734.271816995687, 3742.7380083394173, 3752.6434988676306, 3756.098536351256, 3756.8121593920196, 27083.45833083347, 27336.179914602595, 27586.448271034667, 27745.58381624277, 27823.860880695465, 2610.425706344601, 3052.450558899397, 3075.261937135094, 3089.413011527825, 3101.829399383033, 438354.0, 439980.0, 440480.0, 441811.0, 443181.0, 44248.0, 45292.77353613211, 52456.737716311174, 53356.46643533515, 53512.46487535153, 50554.23060192876, 55696.16455753167, 56025.71987140038, 56161.87676246534, 56315.15527267094, 12349.691257718629, 13253.469861205636, 14225.359858806378, 14259.358328875018, 14285.857141428452, 10930.781384372427, 10970.451477426257, 10987.175961802897, 11112.499937502831, 11121.27711698737, 16791.748123778154, 16965.830341696426, 17037.7444338335, 17047.91476042612, 17091.401800937158, 6208.782692605737, 6766.560173588799, 7098.39663628587, 7552.66013029404, 7611.581363025087, 712.8146681862753, 728.726727477196, 827.962741676626, 843.5191940593803, 854.6026097864523, 20968.89515552443, 22495.550088998127, 27942.44115117727, 28757.424851502852, 29341.413171736873, 990.8910019897805, 1025.3437799808128, 1036.362636978253, 1054.146141625286, 1157.6006277834133, 13573.932130339483, 14753.114813111248, 14960.40158393652, 14993.775093373608, 15498.526632794894, 571845.0, 573993.0, 574731.0, 575318.1234093803, 575518.0, 57081.714591427604, 57332.71333643305, 57679.8464030717, 57693.13460298099, 57802.843943120904, 1322.7090040191142, 1367.1660287224786, 1372.1218420211073, 1375.7592421326228, 1385.889128869688, 27569.310767230978, 27939.301517462227, 28822.279443014093, 28936.55317234088, 29101.12696619105, 47362.52637473581, 47767.0, 47787.76106119586, 47940.7602961983, 48073.03853922832, 475547.0, 478720.0, 479535.0, 480182.0, 480553.0, 24013.51972960566, 24348.147814826396, 24446.144384947154, 24523.01907923663, 24552.87723561371, 44314.77842610766, 44804.0, 45636.315455268865, 45814.31278530758, 46102.769486152356, 36506.634933650326, 36614.08464788402, 37165.25669486541, 37698.81150594339, 37775.0, 34411.31177376388, 36108.81945590255, 36109.45835812517, 37472.625273748, 37487.0, 35587.0, 35809.64190358063, 36669.0, 36797.0, 37205.44191837179, 31112.755489780156, 31195.0, 32819.50770738489, 32827.01518954435, 32855.342893141526, 21824.908754562526, 22554.774452255268, 23609.055637774298, 23803.64294535549, 24829.0], "value": 19081.397816279543, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.50465646987146, 385.57779231741245, 385.88423472958107, 394.1525719702638, 424.1834468647852, 13627.659308517292, 19256.326028588985, 20432.489187770312, 20685.586288274226, 20772.89613551932, 20641.483962900937, 20876.791232087675, 21863.78136218637, 22003.669944950838, 22100.447488812748, 5114.130597798374, 5450.672959622435, 5453.23654688344, 5496.835094947138, 5503.064479038573, 4664.603508701764, 5245.711485868275, 5451.100568406208, 5456.8090116845915, 5464.890702185954, 4640.767961601923, 5433.429489903562, 5459.099248623972, 5473.261109750192, 5482.862928426782, 5422.457754224566, 5454.863628409282, 5456.7544460499175, 5461.317335333083, 5655.434456554352, 5757.712114394305, 5879.176915231871, 5896.292444906596, 5900.350961394243, 5911.0, 13142.4085916134, 13363.265020423873, 13409.731805363936, 13536.59390218295, 13776.8289695375, 2331.2073894875825, 2359.893804778772, 2514.497100579891, 2557.2711777143427, 2583.7803786678155, 9621.139573856804, 9738.805223895482, 10004.449755263458, 10016.89632789783, 10172.94913525435, 1759.129231030648, 1766.2581715679316, 1781.1361489677497, 1807.5842556212012, 1809.1135343681633, 119938.0, 124336.0, 124984.0, 126323.36838315842, 129822.3508882459, 11593.536258549564, 11606.839316068494, 12118.878811211775, 12121.454534545966, 12139.393030348447, 13305.0, 13893.58319250424, 15817.920910395604, 15854.52436426886, 15878.841211587967, 6860.588364698134, 8067.596620168968, 8401.369897257608, 8408.2432581068, 8529.616167272488, 7958.124606293303, 7964.8805267921025, 8152.144024877421, 8196.754097377088, 8227.8354432911, 4137.793110344531, 4802.7838747255755, 4849.563539281482, 4859.402679707619, 4937.1113199624415, 2033.94915127123, 2063.957701360833, 2067.648499755045, 2070.995567149931, 2080.9687854682193, 994.5524513968743, 1006.9748256293651, 1008.8991100889999, 1023.3143793658267, 1053.4311471805293, 17872.0, 19650.70523942142, 20786.792132078786, 20869.852158131143, 21341.573168536852, 1018.9032041956002, 1023.9283250172565, 1050.1703654113164, 1054.894510548939, 1062.4528367890543, 9644.710658680251, 10345.534450949724, 11059.502322395512, 11139.443027848574, 11147.108231341474, 642108.0, 668346.0, 685244.0, 712910.0, 720481.0, 139478.0, 143282.28358858346, 144143.0, 144161.0, 145497.0, 996.0786272697726, 1008.7528555503949, 1022.7931041371253, 1023.125227930124, 1030.283952652899, 17590.73613895767, 17718.84827486209, 18132.546686332418, 18184.636307273515, 18601.53496162607, 53926.0, 54981.0, 55183.0, 56260.15609765939, 56717.0, 77959.44081118233, 79050.0, 81123.18876811156, 81453.59273203833, 88840.11159888576, 6784.457243420416, 7368.852622947404, 7411.925880741339, 7499.2500749924575, 7661.0, 11148.996590306911, 12896.871031289567, 12977.870221297666, 13256.07207495466, 13436.462541498231, 11062.22564420483, 11545.191836571359, 11687.474063666985, 11702.765944681229, 11744.471498782405, 9371.203447707021, 11103.723071846518, 11262.605808796652, 11471.655850324503, 11641.06871450299, 11407.885921140682, 11598.536058557564, 11764.0, 11768.470418831344, 11874.881251187378, 7401.33387995083, 7549.131849837339, 7561.281678240558, 7730.072391313079, 7749.675097470849, 10841.728956776144, 10994.175436842428, 11426.94286528562, 11520.596779112691, 11632.859128035552], "value": 9475.026977004463, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1425.6293363725429, 1433.0255426310111, 1445.9421623135074, 1453.3242042450263, 1466.0983495150485, 6802.251752307242, 8227.91772082279, 8371.539565323905, 8627.698030568923, 8678.913210867897, 6808.046873437723, 7208.747693830717, 8409.621567029482, 8547.914520854789, 8561.357898157632, 1889.9527511812178, 1922.9134688938962, 1952.0, 2302.481941563152, 2304.1704986204954, 2265.399669087693, 2303.5738388398117, 2307.469282065126, 2309.2033248529315, 2317.316391664463, 2257.0181970842655, 2259.819214462839, 2287.501686395405, 2290.3243543154726, 2300.884955752214, 1875.2780179630804, 1879.793222745497, 2260.9660855087186, 2263.852849564773, 2268.0814270220585, 1910.455520176743, 1945.2802463088606, 1981.791911849264, 2209.1826024370926, 2217.81148602369, 8500.617472213766, 8608.31133509324, 8663.95668021656, 8667.609957551926, 8677.956610216972, 5107.719075450848, 5250.18622113573, 5251.317328747238, 5251.396089449724, 5260.973695131538, 3422.6748458896363, 3438.5186073949417, 3469.410200265961, 3504.6530563311976, 3511.4206155984234, 1186.3237954366016, 1200.5317926008863, 1221.4564518789143, 1248.182440501473, 1252.0734656354323, 10841.078508326802, 10852.240343175981, 10884.891151088466, 10952.069074128789, 10992.560297588087, 4166.583341665809, 4460.687751857404, 4519.864404067883, 4529.365888775574, 4531.728096314232, 8747.518886461306, 8763.036066032731, 8820.647174113092, 8832.690855820016, 8863.379563430499, 5052.671576347526, 5110.310108135429, 5110.4123025852505, 5115.846524604268, 5121.487851214849, 4611.700239484489, 4612.377329060601, 4667.043256132485, 4754.548317909793, 4772.952270477251, 3647.434647629622, 3678.3746763050344, 3711.0907827581877, 3729.310077635646, 3776.263628592398, 1141.7830612183825, 1142.3146112332533, 1146.4439746722833, 1155.936423496699, 1157.5080590749067, 849.1211595998112, 871.250724377048, 882.2853488674194, 913.8035322405715, 914.1178762494225, 5121.8719532012, 5227.738613069331, 5433.483819037264, 5707.800226992035, 5925.644461332247, 784.9372050235968, 966.3815158298695, 984.084801134937, 992.4343124419012, 996.5216695985968, 3375.0, 3504.3341765064556, 3711.498947642088, 3731.2164445466215, 3911.3546264865977, 8403.747887563382, 9674.951625241829, 9930.553125109387, 10002.249831262685, 10145.797084058426, 9427.481488518197, 10035.548400322003, 10054.044865737744, 10067.395956242648, 10167.0, 6634.701438435282, 7496.437767167485, 7752.496087754278, 7896.289333959856, 7973.641186146638, 5117.718525481134, 5542.667439953616, 5671.914921276101, 5827.737751801094, 5834.766609335579, 8872.112788721077, 9037.141471560191, 9067.546622668708, 9214.401063930824, 9285.4892980884, 9296.256299495888, 9413.058694130532, 9491.0, 9562.569684364082, 9579.473128977694, 7262.818429539304, 7378.778636640909, 7379.3358597726465, 7383.778486645409, 7416.517926334771, 7702.037245344332, 7920.485168464032, 7923.20767923203, 7931.682732690859, 7989.720359787612, 7108.324709152622, 7135.108111486064, 7186.10173728284, 7191.892121618284, 7282.635868206675, 6985.8253543659785, 7005.194402643558, 7071.7878463646175, 7119.181294151239, 7129.536580122275, 6363.300036996019, 6373.681315934277, 6382.20222472191, 6397.488200944006, 6441.5490915635455, 4344.283193273074, 5003.54968052877, 5021.297018417497, 5086.262491938722, 5147.0, 4476.0, 4807.7836497357, 4834.105690447279, 5021.4225364083595, 5168.302279192261], "value": 4348.963218617705, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [285.6700510909897, 309.4955222986532, 353.45391370332834, 371.0815731065612, 379.6412390291174, 3805.733598648089, 4392.758398288101, 4867.833813952788, 5395.9460405395985, 5414.566834653233, 4099.50805903292, 4771.807048237941, 5196.974015129919, 5219.243209734595, 5253.474652534745, 3498.440249560077, 3556.413191823346, 3598.694111000555, 4089.8159582818844, 4132.648724858391, 3312.817795021273, 3792.886213413602, 4137.213929353413, 4149.7095203335775, 4158.8960275993195, 3732.9253414931686, 3933.567307596162, 3959.4456776051375, 3965.484487016684, 3984.9278375244853, 3604.7290207169576, 3769.9821048317076, 3927.4894263745673, 3964.801759912007, 4022.3765316376007, 3036.9088927332214, 3337.599488061424, 3479.9908026672233, 3484.494748261488, 3489.4068008438626, 4217.325227963511, 4779.617630589544, 4817.85546433604, 5018.372703412074, 5217.947820521784, 3664.926701465982, 3981.28336899358, 4516.5998540452565, 4551.249043907751, 4562.315652652095, 4323.913521729548, 5522.530584900288, 5522.779088836482, 5540.861478463071, 5585.8603534911545, 3091.9536206956914, 3232.531282963957, 3401.0817079388426, 3820.083180036771, 3822.445745366934, 5440.782368705247, 5678.261825962599, 5727.427257274239, 5796.536277097822, 5801.7389217485315, 3212.9196770080935, 3763.513412202165, 3820.7134464915243, 3842.6358642682003, 3869.903252418712, 3475.913102172415, 3881.650651441327, 3898.0060084678794, 3929.8231579578987, 3929.980350098231, 3853.807309634507, 4013.8795836124964, 4245.002424430257, 4442.089371678798, 4587.807170135722, 4787.904241915212, 4890.657653964188, 5433.0, 5632.802851900164, 5659.773609055674, 1104.7737011916765, 1117.9776404471866, 1162.7906976744098, 1254.5985284708806, 1275.9872401276054, 711.5019486359569, 787.6180052674448, 842.8862103615934, 858.1804376820122, 867.2498288979987, 4258.127083947783, 4459.955400445954, 4947.2579113133315, 4948.455669876311, 5027.421846487628, 5171.2501687255135, 5481.506664400223, 5484.61607687458, 5498.670079795225, 5514.696691681916, 4180.665546756313, 4488.912655615155, 4843.425886586873, 4998.300237966688, 5085.64400491962, 2837.333226691767, 2839.957400638992, 2862.9284267893468, 2892.5661150827123, 2905.273681579605, 5269.841904742864, 5370.516653501204, 5610.381304058859, 5699.658020518699, 5758.884822303614, 4494.213512635341, 4860.513948605182, 5163.320347617666, 5296.735163241822, 5622.465865742748, 5541.722913854291, 5709.486146246858, 6004.909926351108, 6020.638761674313, 6041.546883983631, 4655.4413470382415, 5325.653832500875, 5793.94206057934, 5822.475977162076, 5868.23712917318, 5243.8164664236565, 5277.0, 5546.92145859036, 5713.885722285448, 5719.399463056319, 5650.886982260414, 5838.263511352822, 5976.372480889444, 5999.370066142992, 6000.579959402975, 3803.904902377463, 3861.8262178201485, 3885.2220662226646, 3895.9220815584094, 3925.2542017016676, 5345.973270133624, 5666.886662266649, 5751.913721294268, 5770.740316685676, 5795.420457954339, 4449.7775111244955, 5273.709945953009, 5477.890442191214, 5531.640443371168, 5625.7806645016235, 5171.534561889448, 5524.447555244444, 5568.1091025435735, 5587.0, 5607.0, 4701.717896926195, 5389.811356602657, 5437.673739575638, 5480.890382192254, 5496.972515137399, 4276.444062272009, 4939.43196532389, 5077.568406685473, 5565.833025009256, 5567.220589117445, 4751.61987041029, 5016.971520838147, 5504.80733174337, 5523.25436066142, 5539.8892022160135], "value": 3970.251919868006, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [670.8960111182765, 678.3453966921919, 761.0866959648423, 795.3994733975848, 801.0547553886413, 14493.130412175276, 15261.160636165007, 15428.074315541075, 15472.226388680578, 15493.302801373922, 15134.924325378357, 16185.19074046296, 16186.433474828384, 16535.83464165361, 16551.751723724155, 883.5891310540597, 907.9682211122612, 920.6639576554543, 926.5737760630118, 930.0188301342081, 905.2757793764995, 927.1053433436732, 928.5774972387547, 929.8605209218604, 930.4882314726873, 918.9908100919006, 975.5366201054513, 982.6806287956442, 985.3693636072919, 988.7972965542083, 968.9321747477679, 971.820213260549, 972.5817898303727, 973.3770386952356, 973.8490533967175, 649.5712829532533, 687.3092541995263, 725.793148952546, 725.8911163325544, 730.215018854728, 15323.846761532353, 15378.231088445646, 15471.767923481044, 15583.376664933388, 15674.9216253918, 13876.722465550733, 14084.366203520764, 14132.434702611883, 14168.079074860101, 14233.501827436094, 10769.0, 10811.945940270249, 10899.673009809718, 11067.446627668583, 11292.209545331747, 3633.5458067741533, 3657.65252301031, 3668.4313931340685, 3760.793156376398, 3797.9240415191816, 85042.14957850405, 90864.54567727185, 99527.99999999927, 99539.50230248875, 99603.50198248963, 10418.843717344094, 10689.412082335391, 10865.34807911512, 10950.780984380268, 11189.49647265875, 21751.0, 21931.01310441034, 22245.777542224696, 22323.776762232494, 22336.106555737588, 7611.885821712679, 7717.8456430871065, 7742.61286935651, 7818.14000459949, 7954.960225198837, 4228.513720922072, 4262.893427664271, 4353.390525326457, 4421.53573874745, 4431.889202769957, 3124.7968882022597, 3631.2374401376005, 3685.4287585423767, 3735.5517337919623, 3740.9812950935616, 589.4429763873098, 612.702839123025, 627.1909237084202, 665.8568407792347, 695.3359541637724, 630.4105661206786, 642.6401215319436, 669.8445182061004, 679.942204912588, 700.9088818453569, 9381.530923453936, 9739.707808765748, 9830.115289623825, 9874.111329980336, 9975.750606234757, 655.6426747422571, 687.3023880761032, 688.8828899087067, 693.3423266148307, 717.5156769180782, 4440.7335559866515, 4670.346151538719, 4708.976455117702, 4819.1808989549045, 4892.608591312686, 90971.54514227387, 92445.0, 97586.0, 97787.0, 99011.00988990162, 72539.0, 85321.14678853132, 86692.0, 86810.56594716986, 87306.12693873107, 74701.6264918672, 76352.0, 76685.0, 76811.46377072427, 78186.99999999886, 9005.324600654845, 9178.787067606778, 9201.539923003957, 9286.30352723535, 9420.293477989042, 42406.151876961674, 48287.75856120697, 49325.75337123292, 50597.24104138515, 51979.74010129925, 50684.49315506945, 59071.0, 65793.0, 66035.0, 66606.0, 12659.936700316439, 12768.489260429478, 12884.162529435556, 13056.608301750963, 13168.47326106945, 14230.857691422952, 14263.215523146326, 14269.857301427268, 14526.20105894186, 14563.708725825636, 13190.736185276433, 13236.470541178245, 13380.732385352434, 13484.325783710972, 13848.861511384757, 13074.150180238254, 13203.075784695364, 13383.464661413434, 13822.30888455553, 13840.446382145004, 12712.74574508523, 12910.031747618777, 13059.542915997894, 13767.931160344135, 13802.0, 8701.521416322163, 10707.732306692084, 10773.461326933779, 10883.78232435331, 11449.198556100993, 8613.82772344537, 9102.362834601514, 9585.760355991157, 9771.609135634495, 10716.196285278791], "value": 7452.921777509639, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1621.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 118.0, 118.0, 118.0, 117.0, 118.0, 117.0, 132.0, 173.0, 156.0], "value": 127.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 115.0, 114.0, 155.0, 117.0, 119.0, 117.0, 114.0, 137.0, 116.0], "value": 124.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 205.0, 229.0, 240.0, 211.0, 287.0, 253.0, 199.0, 193.0, 199.0], "value": 221.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 111.0, 85.0, 85.0, 64.0, 64.0, 74.0, 62.0], "value": 77.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 89.0, 70.0, 74.0, 71.0, 82.0, 103.0, 99.0, 116.0], "value": 86.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 85.0, 87.0, 90.0, 136.0, 108.0, 89.0, 88.0, 89.0], "value": 95.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 156.0, 121.0, 106.0, 95.0, 153.0, 154.0, 104.0, 123.0, 107.0], "value": 121.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 91.0, 104.0, 144.0, 121.0, 94.0, 94.0, 93.0, 95.0], "value": 102.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 66.0, 65.0, 64.0, 65.0, 65.0, 64.0, 65.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 69.0, 75.0, 76.0, 73.0, 73.0, 70.0, 70.0, 69.0, 72.0], "value": 72.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 93.0, 92.0, 93.0, 110.0, 82.0, 100.0, 120.0, 108.0], "value": 98.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 152.0, 145.0, 146.0, 144.0, 162.0, 163.0, 159.0, 210.0, 160.0], "value": 158.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 200.0, 175.0, 229.0, 178.0, 174.0, 243.0, 165.0, 239.0, 174.0], "value": 197.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 68.0, 67.0, 72.0, 70.0, 62.0, 85.0, 87.0, 89.0, 71.0], "value": 74.2, "unit": "score"}]}]} 22:21:07 INFO - Return code: 0 22:21:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:21:07 ERROR - # TBPL SUCCESS # 22:21:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:21:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:21:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:21:07 INFO - 2017-03-06 22:21:07.535 screenresolution[1654:10587] starting screenresolution argv=screenresolution get 22:21:07 INFO - 2017-03-06 22:21:07.538 screenresolution[1654:10587] Display 0: 1600x1200x32@60 22:21:07 INFO - 2017-03-06 22:21:07.544 screenresolution[1655:10590] starting screenresolution argv=screenresolution list 22:21:07 INFO - Available Modes on Display 0 22:21:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:21:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:21:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:21:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:21:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:21:07 INFO - Intel Iris: 22:21:07 INFO - Chipset Model: Intel Iris 22:21:07 INFO - Type: GPU 22:21:07 INFO - Bus: Built-In 22:21:07 INFO - VRAM (Dynamic, Max): 1536 MB 22:21:07 INFO - Vendor: Intel (0x8086) 22:21:07 INFO - Device ID: 0x0a2e 22:21:07 INFO - Revision ID: 0x0009 22:21:07 INFO - Displays: 22:21:07 INFO - Display: 22:21:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:21:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:21:07 INFO - Display Serial Number: 1600x1200 60 22:21:07 INFO - Main Display: Yes 22:21:07 INFO - Mirror: Off 22:21:07 INFO - Online: Yes 22:21:07 INFO - Rotation: Supported 22:21:07 INFO - Return code: 0 22:21:07 INFO - Running post-action listener: _resource_record_post_action 22:21:07 INFO - [mozharness: 2017-03-07 06:21:07.777801Z] Finished run-tests step (success) 22:21:07 INFO - Running post-run listener: _resource_record_post_run 22:21:07 INFO - Total resource usage - Wall time: 1201s; CPU: 55.0%; Read bytes: 37448192; Write bytes: 554205696; Read time: 243; Write time: 1694 22:21:07 INFO - TinderboxPrint: CPU usage
54.6% 22:21:07 INFO - TinderboxPrint: I/O read bytes / time
37,448,192 / 243 22:21:07 INFO - TinderboxPrint: I/O write bytes / time
554,205,696 / 1,694 22:21:07 INFO - TinderboxPrint: CPU idle
2,182.4 (45.4%) 22:21:07 INFO - TinderboxPrint: CPU system
967.1 (20.1%) 22:21:07 INFO - TinderboxPrint: CPU user
1,656.1 (34.5%) 22:21:07 INFO - TinderboxPrint: Swap in / out
289,943,552 / 0 22:21:07 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 253712896; Write bytes: 246687232; Read time: 18180; Write time: 518 22:21:07 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 35195392; Write bytes: 298183680; Read time: 220; Write time: 1142 22:21:08 INFO - Running post-run listener: _upload_blobber_files 22:21:08 INFO - Blob upload gear active. 22:21:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:21:08 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 22:21:08 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'] 22:21:08 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 22:21:08 INFO - (blobuploader) - INFO - Open directory for files ... 22:21:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:21:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:21:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:21:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:21:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:21:10 INFO - (blobuploader) - INFO - Done attempting. 22:21:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:21:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:21:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:21:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:21:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:21:11 INFO - (blobuploader) - INFO - Done attempting. 22:21:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 22:21:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:21:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:21:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 22:21:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:21:12 INFO - (blobuploader) - INFO - Done attempting. 22:21:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:21:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:21:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:21:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:21:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:21:14 INFO - (blobuploader) - INFO - Done attempting. 22:21:14 INFO - (blobuploader) - INFO - Iteration through files over. 22:21:14 INFO - Return code: 0 22:21:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:21:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:21:14 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/737d721cdc956f7697eeebc4f9658b21aacebe4a28373dedd0d5c1e3f0fc0633370f2ddf01cf1a9fde1feedbe0cf21c05513169f65429bb32aea81927121cdd5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb915e6e09a9c2f72288f4d214a3503402d0fa91ddfeeefecd2f45bc424eaac29a5caf51b4441d84148c68df47db205a410f7577f7ee0de4e3e232a4d6d4f9bc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54da67991c22bf637715a5e0f4feba3a7f0e24d50efd75c32b8bbe991a910b10a7263e1e7943c360a818944eab1196371bf6464383ba9bee900e0c5b44524e4b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5fa78321f0f6da1e121c6e8ae8512f204dd1cb918f43aa32afa6096863f83bd9b059a241cadf5bba408b7286202d2122b9ca85ba6d13603ccecddbad52056bd5"} 22:21:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:21:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:21:14 INFO - Contents: 22:21:14 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/737d721cdc956f7697eeebc4f9658b21aacebe4a28373dedd0d5c1e3f0fc0633370f2ddf01cf1a9fde1feedbe0cf21c05513169f65429bb32aea81927121cdd5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb915e6e09a9c2f72288f4d214a3503402d0fa91ddfeeefecd2f45bc424eaac29a5caf51b4441d84148c68df47db205a410f7577f7ee0de4e3e232a4d6d4f9bc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54da67991c22bf637715a5e0f4feba3a7f0e24d50efd75c32b8bbe991a910b10a7263e1e7943c360a818944eab1196371bf6464383ba9bee900e0c5b44524e4b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5fa78321f0f6da1e121c6e8ae8512f204dd1cb918f43aa32afa6096863f83bd9b059a241cadf5bba408b7286202d2122b9ca85ba6d13603ccecddbad52056bd5"} 22:21:14 INFO - Running post-run listener: copy_logs_to_upload_dir 22:21:14 INFO - Copying logs to upload dir... 22:21:14 INFO - mkdir: /builds/slave/test/build/upload/logs 22:21:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.228544 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-06 22:21:14.395464) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-06 22:21:14.399073) ========= 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.iuV7vaJeqY/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.KZisH3BaMS/Listeners TMPDIR=/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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/737d721cdc956f7697eeebc4f9658b21aacebe4a28373dedd0d5c1e3f0fc0633370f2ddf01cf1a9fde1feedbe0cf21c05513169f65429bb32aea81927121cdd5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb915e6e09a9c2f72288f4d214a3503402d0fa91ddfeeefecd2f45bc424eaac29a5caf51b4441d84148c68df47db205a410f7577f7ee0de4e3e232a4d6d4f9bc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54da67991c22bf637715a5e0f4feba3a7f0e24d50efd75c32b8bbe991a910b10a7263e1e7943c360a818944eab1196371bf6464383ba9bee900e0c5b44524e4b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5fa78321f0f6da1e121c6e8ae8512f204dd1cb918f43aa32afa6096863f83bd9b059a241cadf5bba408b7286202d2122b9ca85ba6d13603ccecddbad52056bd5"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011298 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/737d721cdc956f7697eeebc4f9658b21aacebe4a28373dedd0d5c1e3f0fc0633370f2ddf01cf1a9fde1feedbe0cf21c05513169f65429bb32aea81927121cdd5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb915e6e09a9c2f72288f4d214a3503402d0fa91ddfeeefecd2f45bc424eaac29a5caf51b4441d84148c68df47db205a410f7577f7ee0de4e3e232a4d6d4f9bc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54da67991c22bf637715a5e0f4feba3a7f0e24d50efd75c32b8bbe991a910b10a7263e1e7943c360a818944eab1196371bf6464383ba9bee900e0c5b44524e4b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5fa78321f0f6da1e121c6e8ae8512f204dd1cb918f43aa32afa6096863f83bd9b059a241cadf5bba408b7286202d2122b9ca85ba6d13603ccecddbad52056bd5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-06 22:21:14.432922) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-06 22:21:14.433238) ========= 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.iuV7vaJeqY/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.KZisH3BaMS/Listeners TMPDIR=/var/folders/bb/ktj0_qbn2d57sqn3vz5wnz8000000w/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.005113 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-06 22:21:14.481546) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-06 22:21:14.481864) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-06 22:21:14.482199) ========= ========= Total master_lag: 0.17 =========