builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0082 starttime: 1488394861.42 results: success (0) buildid: 20170301110035 builduid: a8b68e0420434e1baa9f3eea5c6e4fa8 revision: fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-01 11:01:01.424909) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-01 11:01:01.425385) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-01 11:01:01.425676) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-01 11:01:01.452970) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 11:01:01.453353) ========= 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.kMd4P7D7jn/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.5s1tQuJeH2/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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-01 11:01:01-- 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% 14.4M=0.001s 2017-03-01 11:01:01 (14.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.116455 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 11:01:01.594269) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 11:01:01.594574) ========= 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.kMd4P7D7jn/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.5s1tQuJeH2/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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.027518 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 11:01:01.644016) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-01 11:01:01.644360) ========= 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.kMd4P7D7jn/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.5s1tQuJeH2/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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-01 11:01:01,746 truncating revision to first 12 chars 2017-03-01 11:01:01,747 Setting DEBUG logging. 2017-03-01 11:01:01,747 attempt 1/10 2017-03-01 11:01:01,747 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-01 11:01:03,456 unpacking tar archive at: mozilla-central-fc9f6f7e8e86/testing/mozharness/ program finished with exit code 0 elapsedTime=1.982287 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-01 11:01:03.650489) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-01 11:01:03.650828) ========= script_repo_revision: fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-01 11:01:03.651243) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-01 11:01:03.651501) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-01 11:01:03.671675) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-01 11:01:03.672013) ========= /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.kMd4P7D7jn/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.5s1tQuJeH2/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 11:01:03 INFO - MultiFileLogger online at 20170301 11:01:03 in /builds/slave/test 11:01:03 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 11:01:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:01:03 INFO - {'append_to_log': False, 11:01:03 INFO - 'base_work_dir': '/builds/slave/test', 11:01:03 INFO - 'blob_upload_branch': 'Firefox', 11:01:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:01:03 INFO - 'branch': 'Firefox', 11:01:03 INFO - 'buildbot_json_path': 'buildprops.json', 11:01:03 INFO - 'config_files': ('talos/mac_config.py',), 11:01:03 INFO - 'default_actions': ('clobber', 11:01:03 INFO - 'read-buildbot-config', 11:01:03 INFO - 'download-and-extract', 11:01:03 INFO - 'populate-webroot', 11:01:03 INFO - 'create-virtualenv', 11:01:03 INFO - 'install', 11:01:03 INFO - 'run-tests'), 11:01:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:01:03 INFO - 'download_minidump_stackwalk': True, 11:01:03 INFO - 'download_symbols': 'ondemand', 11:01:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:01:03 INFO - 'tooltool.py': '/tools/tooltool.py', 11:01:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:01:03 INFO - '/tools/misc-python/virtualenv.py')}, 11:01:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:01:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:01:03 INFO - 'installer_path': 'installer.exe', 11:01:03 INFO - 'log_level': 'info', 11:01:03 INFO - 'log_name': 'talos', 11:01:03 INFO - 'log_to_console': True, 11:01:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:01:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:01:03 INFO - 'opt_config_files': (), 11:01:03 INFO - 'pip_index': False, 11:01:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:01:03 INFO - 'cmd': ('bash', 11:01:03 INFO - '-c', 11:01:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:01:03 INFO - 'enabled': True, 11:01:03 INFO - 'halt_on_failure': False, 11:01:03 INFO - 'name': 'check_screen_resolution'},), 11:01:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:01:03 INFO - 'cmd': ('bash', 11:01:03 INFO - '-c', 11:01:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:01:03 INFO - 'enabled': True, 11:01:03 INFO - 'halt_on_failure': False, 11:01:03 INFO - 'name': 'check_screen_resolution'},), 11:01:03 INFO - 'run_cmd_checks_enabled': True, 11:01:03 INFO - 'sps_profile': False, 11:01:03 INFO - 'sps_profile_interval': 0, 11:01:03 INFO - 'suite': 'dromaeojs-e10s', 11:01:03 INFO - 'system_bits': '32', 11:01:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:01:03 INFO - 'title': 't-yosemite-r7-0082', 11:01:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:01:03 INFO - 'use_talos_json': True, 11:01:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:01:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:01:03 INFO - 'work_dir': 'build'} 11:01:03 INFO - [mozharness: 2017-03-01 19:01:03.890315Z] Running clobber step. 11:01:03 INFO - Running pre-action listener: _resource_record_pre_action 11:01:03 INFO - Running main action method: clobber 11:01:03 INFO - rmtree: /builds/slave/test/build 11:01:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:01:06 INFO - Running post-action listener: _resource_record_post_action 11:01:06 INFO - [mozharness: 2017-03-01 19:01:06.504494Z] Finished clobber step (success) 11:01:06 INFO - [mozharness: 2017-03-01 19:01:06.504644Z] Running read-buildbot-config step. 11:01:06 INFO - Running pre-action listener: _resource_record_pre_action 11:01:06 INFO - Running main action method: read_buildbot_config 11:01:06 INFO - Using buildbot properties: 11:01:06 INFO - { 11:01:06 INFO - "project": "", 11:01:06 INFO - "product": "firefox", 11:01:06 INFO - "script_repo_revision": "production", 11:01:06 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 11:01:06 INFO - "repository": "", 11:01:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 11:01:06 INFO - "buildid": "20170301110035", 11:01:06 INFO - "stage_platform": "macosx64", 11:01:06 INFO - "basedir": "/builds/slave/test", 11:01:06 INFO - "buildnumber": 10, 11:01:06 INFO - "platform": "yosemite_r7", 11:01:06 INFO - "mozci_request": { 11:01:06 INFO - "priority": -1, 11:01:06 INFO - "type": "trigger_all_talos_jobs", 11:01:06 INFO - "times": 1 11:01:06 INFO - }, 11:01:06 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 11:01:06 INFO - "slavebuilddir": "test", 11:01:06 INFO - "branch": "mozilla-central", 11:01:06 INFO - "revision": "fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd", 11:01:06 INFO - "slavename": "t-yosemite-r7-0082", 11:01:06 INFO - "builduid": "a8b68e0420434e1baa9f3eea5c6e4fa8", 11:01:06 INFO - "repo_path": "mozilla-central" 11:01:06 INFO - } 11:01:06 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.dmg. 11:01:06 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. 11:01:06 INFO - Running post-action listener: _resource_record_post_action 11:01:06 INFO - [mozharness: 2017-03-01 19:01:06.508674Z] Finished read-buildbot-config step (success) 11:01:06 INFO - [mozharness: 2017-03-01 19:01:06.508819Z] Running download-and-extract step. 11:01:06 INFO - Running pre-action listener: _resource_record_pre_action 11:01:06 INFO - Running main action method: download_and_extract 11:01:06 INFO - mkdir: /builds/slave/test/build/tests 11:01:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:01:06 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.test_packages.json 11:01:06 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 11:01:06 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 11:01:06 INFO - Downloaded 1199 bytes. 11:01:06 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 11:01:06 INFO - Using the following test package requirements: 11:01:06 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 11:01:06 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 11:01:06 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 11:01:06 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 11:01:06 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 11:01:06 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 11:01:06 INFO - u'jsshell-mac.zip'], 11:01:06 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 11:01:06 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 11:01:06 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 11:01:06 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 11:01:06 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 11:01:06 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 11:01:06 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 11:01:06 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 11:01:06 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 11:01:06 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 11:01:06 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 11:01:06 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:01:06 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 11:01:06 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 11:01:06 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 11:01:07 INFO - Expected file size: 51504348 11:01:07 INFO - Obtained file size: 51504348 11:01:23 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 11:01:23 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 11:01:23 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 11:01:23 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 11:01:24 INFO - Expected file size: 51504348 11:01:24 INFO - Obtained file size: 51504348 11:01:39 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 11:01:39 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 11:01:39 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 11:01:40 INFO - Expected file size: 14403647 11:01:40 INFO - Obtained file size: 14403647 11:01:40 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')]} 11:01:40 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.dmg 11:01:40 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 11:01:40 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 11:01:41 INFO - Downloaded 70417704 bytes. 11:01:41 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 11:01:41 INFO - mkdir: /builds/slave/test/properties 11:01:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:01:41 INFO - Writing to file /builds/slave/test/properties/build_url 11:01:41 INFO - Contents: 11:01:41 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.dmg 11:01:42 INFO - Running post-action listener: _resource_record_post_action 11:01:42 INFO - Running post-action listener: set_extra_try_arguments 11:01:42 INFO - [mozharness: 2017-03-01 19:01:42.357237Z] Finished download-and-extract step (success) 11:01:42 INFO - [mozharness: 2017-03-01 19:01:42.357366Z] Running populate-webroot step. 11:01:42 INFO - Running pre-action listener: _resource_record_pre_action 11:01:42 INFO - Running main action method: populate_webroot 11:01:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:01:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:01:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:01:42 INFO - '--tppagecycles', 11:01:42 INFO - '7'], 11:01:42 INFO - 'tests': ['tsvgm']}}, 11:01:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:01:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:01:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:01:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:01:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:01:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:01:42 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:01:42 INFO - 'talos_options': ['--disable-e10s'], 11:01:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:01:42 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:01:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:01:42 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:01:42 INFO - 'talos_options': ['--disable-e10s'], 11:01:42 INFO - 'tests': ['damp', 'tps']}, 11:01:42 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:01:42 INFO - 'tests': ['damp', 'tps']}, 11:01:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:01:42 INFO - 'tests': ['dromaeo_dom']}, 11:01:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:01:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:01:42 INFO - 'tests': ['basic_compositor_video']}, 11:01:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:01:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:01:42 INFO - 'tests': ['a11yr', 11:01:42 INFO - 'ts_paint', 11:01:42 INFO - 'tpaint', 11:01:42 INFO - 'sessionrestore', 11:01:42 INFO - 'sessionrestore_no_auto_restore', 11:01:42 INFO - 'tabpaint']}, 11:01:42 INFO - 'other-e10s': {'tests': ['a11yr', 11:01:42 INFO - 'ts_paint', 11:01:42 INFO - 'tpaint', 11:01:42 INFO - 'sessionrestore', 11:01:42 INFO - 'sessionrestore_no_auto_restore', 11:01:42 INFO - 'tabpaint']}, 11:01:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:01:42 INFO - 'ts_paint', 11:01:42 INFO - 'tpaint', 11:01:42 INFO - 'sessionrestore', 11:01:42 INFO - 'sessionrestore_no_auto_restore', 11:01:42 INFO - 'tabpaint']}, 11:01:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:01:42 INFO - 'ts_paint', 11:01:42 INFO - 'tpaint', 11:01:42 INFO - 'sessionrestore', 11:01:42 INFO - 'sessionrestore_no_auto_restore', 11:01:42 INFO - 'tabpaint']}, 11:01:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:01:42 INFO - 'tests': ['a11yr', 11:01:42 INFO - 'ts_paint', 11:01:42 INFO - 'tpaint', 11:01:42 INFO - 'sessionrestore', 11:01:42 INFO - 'sessionrestore_no_auto_restore', 11:01:42 INFO - 'tabpaint']}, 11:01:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:01:42 INFO - 'tests': ['a11yr', 11:01:42 INFO - 'ts_paint', 11:01:42 INFO - 'tpaint', 11:01:42 INFO - 'sessionrestore', 11:01:42 INFO - 'sessionrestore_no_auto_restore', 11:01:42 INFO - 'tabpaint']}, 11:01:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:01:42 INFO - 'tests': ['tsvgx', 11:01:42 INFO - 'tsvgr_opacity', 11:01:42 INFO - 'tart', 11:01:42 INFO - 'tscrollx', 11:01:42 INFO - 'cart', 11:01:42 INFO - 'tsvg_static']}, 11:01:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:01:42 INFO - 'tsvgr_opacity', 11:01:42 INFO - 'tart', 11:01:42 INFO - 'tscrollx', 11:01:42 INFO - 'cart', 11:01:42 INFO - 'tsvg_static']}, 11:01:42 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:01:42 INFO - 'talos_options': ['--disable-e10s'], 11:01:42 INFO - 'tests': ['tp5o']}, 11:01:42 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:01:42 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:01:42 INFO - 'talos_options': ['--disable-e10s', 11:01:42 INFO - '--xperf_path', 11:01:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:01:42 INFO - 'tests': ['tp5n']}, 11:01:42 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:01:42 INFO - 'talos_options': ['--xperf_path', 11:01:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:01:42 INFO - 'tests': ['tp5n']}}, 11:01:42 INFO - 'talos.zip': {'path': '', 11:01:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:01:42 INFO - Running post-action listener: _resource_record_post_action 11:01:42 INFO - [mozharness: 2017-03-01 19:01:42.376000Z] Finished populate-webroot step (success) 11:01:42 INFO - [mozharness: 2017-03-01 19:01:42.376131Z] Running create-virtualenv step. 11:01:42 INFO - Running pre-action listener: _resource_record_pre_action 11:01:42 INFO - Running main action method: create_virtualenv 11:01:42 INFO - Creating virtualenv /builds/slave/test/build/venv 11:01:42 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:01:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:01:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:01:42 INFO - Using real prefix '/tools/python27' 11:01:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:01:43 INFO - Installing distribute.............................................................................................................................................................................................done. 11:01:46 INFO - Installing pip.................done. 11:01:46 INFO - Return code: 0 11:01:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:01:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:01:46 INFO - Reading from file tmpfile_stdout 11:01:46 INFO - Output received: 11:01:46 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 11:01:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:01:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:01:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:01:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:01:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:01:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:01:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:01:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x1083c6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083c9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85ebde2aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081a4d30>, '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 0x1080cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108199be8>, '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.kMd4P7D7jn/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.5s1tQuJeH2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:01:46 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 11:01:46 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 11:01:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kMd4P7D7jn/Render', 11:01:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:01:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:01:46 INFO - 'HOME': '/Users/cltbld', 11:01:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:01:46 INFO - 'LOGNAME': 'cltbld', 11:01:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:01:46 INFO - 'MOZ_NO_REMOTE': '1', 11:01:46 INFO - 'NO_EM_RESTART': '1', 11:01:46 INFO - 'PAGER': '/bin/cat', 11:01:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:01:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:01:46 INFO - 'PWD': '/builds/slave/test', 11:01:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:01:46 INFO - 'SHELL': '/bin/bash', 11:01:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5s1tQuJeH2/Listeners', 11:01:46 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 11:01:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:01:46 INFO - 'USER': 'cltbld', 11:01:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:01:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:01:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:01:46 INFO - 'XPC_FLAGS': '0x0', 11:01:46 INFO - 'XPC_SERVICE_NAME': '0', 11:01:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:01:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:01:46 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 11:01:46 INFO - Cleaning up... 11:01:46 INFO - Return code: 0 11:01:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:01:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:01:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:01:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:01:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:01:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:01:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:01:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x1083c6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083c9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85ebde2aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081a4d30>, '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 0x1080cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108199be8>, '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.kMd4P7D7jn/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.5s1tQuJeH2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:01:46 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 11:01:46 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 11:01:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kMd4P7D7jn/Render', 11:01:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:01:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:01:46 INFO - 'HOME': '/Users/cltbld', 11:01:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:01:46 INFO - 'LOGNAME': 'cltbld', 11:01:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:01:46 INFO - 'MOZ_NO_REMOTE': '1', 11:01:46 INFO - 'NO_EM_RESTART': '1', 11:01:46 INFO - 'PAGER': '/bin/cat', 11:01:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:01:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:01:46 INFO - 'PWD': '/builds/slave/test', 11:01:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:01:46 INFO - 'SHELL': '/bin/bash', 11:01:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5s1tQuJeH2/Listeners', 11:01:46 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 11:01:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:01:46 INFO - 'USER': 'cltbld', 11:01:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:01:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:01:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:01:46 INFO - 'XPC_FLAGS': '0x0', 11:01:46 INFO - 'XPC_SERVICE_NAME': '0', 11:01:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:01:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:01:46 INFO - Downloading/unpacking psutil>=3.1.1 11:01: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 11:01: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 11:01: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 11:01: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 11:01: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 11:01: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 11:01:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:01:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:01:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:01:50 INFO - Installing collected packages: psutil 11:01:50 INFO - Running setup.py install for psutil 11:01:50 INFO - building 'psutil._psutil_osx' extension 11:01:50 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 11:01:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 11:01:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 11:01:51 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 11:01:51 INFO - building 'psutil._psutil_posix' extension 11:01:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 11:01:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:01:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:01:51 INFO - ^ 11:01:51 INFO - 1 warning generated. 11:01:51 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 11:01:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:01:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:01:51 INFO - Successfully installed psutil 11:01:51 INFO - Cleaning up... 11:01:51 INFO - Return code: 0 11:01:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:01:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:01:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:01:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:01:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1083c6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083c9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85ebde2aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081a4d30>, '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 0x1080cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108199be8>, '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.kMd4P7D7jn/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.5s1tQuJeH2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:01:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:01:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:01:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kMd4P7D7jn/Render', 11:01:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:01:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:01:51 INFO - 'HOME': '/Users/cltbld', 11:01:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:01:51 INFO - 'LOGNAME': 'cltbld', 11:01:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:01:51 INFO - 'MOZ_NO_REMOTE': '1', 11:01:51 INFO - 'NO_EM_RESTART': '1', 11:01:51 INFO - 'PAGER': '/bin/cat', 11:01:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:01:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:01:51 INFO - 'PWD': '/builds/slave/test', 11:01:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:01:51 INFO - 'SHELL': '/bin/bash', 11:01:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5s1tQuJeH2/Listeners', 11:01:51 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 11:01:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:01:51 INFO - 'USER': 'cltbld', 11:01:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:01:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:01:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:01:51 INFO - 'XPC_FLAGS': '0x0', 11:01:51 INFO - 'XPC_SERVICE_NAME': '0', 11:01:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:01:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:01:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:01:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:01:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:01:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:01:54 INFO - Installing collected packages: mozsystemmonitor 11:01:54 INFO - Running setup.py install for mozsystemmonitor 11:01:54 INFO - Successfully installed mozsystemmonitor 11:01:54 INFO - Cleaning up... 11:01:54 INFO - Return code: 0 11:01:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:01: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')]} 11:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:01:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:01: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')]} 11:01:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:01:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:01:54 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 0x1083c6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083c9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85ebde2aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081a4d30>, '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 0x1080cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108199be8>, '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.kMd4P7D7jn/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.5s1tQuJeH2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:01:54 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 11:01:54 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 11:01:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kMd4P7D7jn/Render', 11:01:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:01:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:01:54 INFO - 'HOME': '/Users/cltbld', 11:01:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:01:54 INFO - 'LOGNAME': 'cltbld', 11:01:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:01:54 INFO - 'MOZ_NO_REMOTE': '1', 11:01:54 INFO - 'NO_EM_RESTART': '1', 11:01:54 INFO - 'PAGER': '/bin/cat', 11:01:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:01:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:01:54 INFO - 'PWD': '/builds/slave/test', 11:01:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:01:54 INFO - 'SHELL': '/bin/bash', 11:01:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5s1tQuJeH2/Listeners', 11:01:54 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 11:01:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:01:54 INFO - 'USER': 'cltbld', 11:01:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:01:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:01:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:01:54 INFO - 'XPC_FLAGS': '0x0', 11:01:54 INFO - 'XPC_SERVICE_NAME': '0', 11:01:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:01:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:01:55 INFO - Downloading/unpacking jsonschema==2.5.1 11:01:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:55 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 11:01:55 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 11:01:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:01:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:57 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 11:01:57 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 11:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:58 INFO - Downloading functools32-3.2.3-2.tar.gz 11:01:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:01:58 INFO - warning: no files found matching '*.txt' 11:01:58 INFO - no previously-included directories found matching 'build' 11:01:58 INFO - no previously-included directories found matching 'dist' 11:01:58 INFO - no previously-included directories found matching '.git*' 11:01:58 INFO - Installing collected packages: jsonschema, functools32 11:01:58 INFO - Running setup.py install for functools32 11:01:58 INFO - warning: no files found matching '*.txt' 11:01:58 INFO - no previously-included directories found matching 'build' 11:01:58 INFO - no previously-included directories found matching 'dist' 11:01:58 INFO - no previously-included directories found matching '.git*' 11:01:58 INFO - Successfully installed jsonschema functools32 11:01:58 INFO - Cleaning up... 11:01:58 INFO - Return code: 0 11:01:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:01:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:01:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:01:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:01:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:01:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:01:58 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 0x1083c6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083c9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85ebde2aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081a4d30>, '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 0x1080cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108199be8>, '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.kMd4P7D7jn/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.5s1tQuJeH2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:01:58 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 11:01:58 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 11:01:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kMd4P7D7jn/Render', 11:01:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:01:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:01:58 INFO - 'HOME': '/Users/cltbld', 11:01:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:01:58 INFO - 'LOGNAME': 'cltbld', 11:01:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:01:58 INFO - 'MOZ_NO_REMOTE': '1', 11:01:58 INFO - 'NO_EM_RESTART': '1', 11:01:58 INFO - 'PAGER': '/bin/cat', 11:01:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:01:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:01:58 INFO - 'PWD': '/builds/slave/test', 11:01:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:01:58 INFO - 'SHELL': '/bin/bash', 11:01:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5s1tQuJeH2/Listeners', 11:01:58 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 11:01:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:01:58 INFO - 'USER': 'cltbld', 11:01:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:01:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:01:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:01:58 INFO - 'XPC_FLAGS': '0x0', 11:01:58 INFO - 'XPC_SERVICE_NAME': '0', 11:01:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:01:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:01:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:01:59 INFO - Cleaning up... 11:01:59 INFO - Return code: 0 11:01:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:01: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')]} 11:01:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:01:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:01: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')]} 11:01:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:01:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:01:59 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 0x1083c6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083c9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85ebde2aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081a4d30>, '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 0x1080cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108199be8>, '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.kMd4P7D7jn/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.5s1tQuJeH2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:01:59 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 11:01:59 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 11:01:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kMd4P7D7jn/Render', 11:01:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:01:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:01:59 INFO - 'HOME': '/Users/cltbld', 11:01:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:01:59 INFO - 'LOGNAME': 'cltbld', 11:01:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:01:59 INFO - 'MOZ_NO_REMOTE': '1', 11:01:59 INFO - 'NO_EM_RESTART': '1', 11:01:59 INFO - 'PAGER': '/bin/cat', 11:01:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:01:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:01:59 INFO - 'PWD': '/builds/slave/test', 11:01:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:01:59 INFO - 'SHELL': '/bin/bash', 11:01:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5s1tQuJeH2/Listeners', 11:01:59 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 11:01:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:01:59 INFO - 'USER': 'cltbld', 11:01:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:01:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:01:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:01:59 INFO - 'XPC_FLAGS': '0x0', 11:01:59 INFO - 'XPC_SERVICE_NAME': '0', 11:01:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:01:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:01:59 INFO - Downloading/unpacking blobuploader==1.2.4 11:01:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:59 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 11:01:59 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 11:01:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:02 INFO - Downloading blobuploader-1.2.4.tar.gz 11:02:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:02:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:02:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:02 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 11:02:02 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 11:02:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:02:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:03 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 11:02:03 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 11:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:04 INFO - Downloading docopt-0.6.1.tar.gz 11:02:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:02:04 INFO - Installing collected packages: blobuploader, requests, docopt 11:02:04 INFO - Running setup.py install for blobuploader 11:02:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:02:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:02:04 INFO - Running setup.py install for requests 11:02:04 INFO - Running setup.py install for docopt 11:02:05 INFO - Successfully installed blobuploader requests docopt 11:02:05 INFO - Cleaning up... 11:02:05 INFO - Return code: 0 11:02:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:02: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')]} 11:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02: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')]} 11:02:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:05 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 0x1083c6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083c9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85ebde2aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081a4d30>, '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 0x1080cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108199be8>, '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.kMd4P7D7jn/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.5s1tQuJeH2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:02:05 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 11:02:05 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 11:02:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kMd4P7D7jn/Render', 11:02:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:02:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:02:05 INFO - 'HOME': '/Users/cltbld', 11:02:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:02:05 INFO - 'LOGNAME': 'cltbld', 11:02:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:05 INFO - 'MOZ_NO_REMOTE': '1', 11:02:05 INFO - 'NO_EM_RESTART': '1', 11:02:05 INFO - 'PAGER': '/bin/cat', 11:02:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:02:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:05 INFO - 'PWD': '/builds/slave/test', 11:02:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:02:05 INFO - 'SHELL': '/bin/bash', 11:02:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5s1tQuJeH2/Listeners', 11:02:05 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 11:02:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:02:05 INFO - 'USER': 'cltbld', 11:02:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:02:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:02:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:05 INFO - 'XPC_FLAGS': '0x0', 11:02:05 INFO - 'XPC_SERVICE_NAME': '0', 11:02:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:02:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:02:05 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-tU8QAj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:02:05 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-gwVbaj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:02:05 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-dZJrea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:02:05 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-i0WZiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:02:05 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-cFTtby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:02:05 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-cbZdCk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:02:06 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-gMOrJT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:02:06 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-Gc2yjv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:02:06 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-68H2kH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:02:06 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-9apCXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:02:06 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-foyPgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:02:06 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-J4N7BM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:02:06 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-fPtMQJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:02:06 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-PgLmPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:02:06 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-MIGCxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:02:06 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-vgjF20-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:02:07 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-JNuRPL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:02:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:02:07 INFO - Running setup.py install for manifestparser 11:02:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:02:07 INFO - Running setup.py install for mozcrash 11:02:07 INFO - Running setup.py install for mozdebug 11:02:07 INFO - Running setup.py install for mozdevice 11:02:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:02:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:02:07 INFO - Running setup.py install for mozfile 11:02:07 INFO - Running setup.py install for mozhttpd 11:02:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:02:08 INFO - Running setup.py install for mozinfo 11:02:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:02:08 INFO - Running setup.py install for mozInstall 11:02:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:02:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:02:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:02:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:02:08 INFO - Running setup.py install for mozleak 11:02:08 INFO - Running setup.py install for mozlog 11:02:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:02:08 INFO - Running setup.py install for moznetwork 11:02:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:02:08 INFO - Running setup.py install for mozprocess 11:02:08 INFO - Running setup.py install for mozprofile 11:02:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:02:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:02:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:02:09 INFO - Running setup.py install for mozrunner 11:02:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:02:09 INFO - Running setup.py install for mozscreenshot 11:02:09 INFO - Running setup.py install for moztest 11:02:09 INFO - Running setup.py install for mozversion 11:02:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:02:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:02:09 INFO - Cleaning up... 11:02:09 INFO - Return code: 0 11:02:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:02:09 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')]} 11:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:09 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')]} 11:02:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:09 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 0x1083c6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083c9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85ebde2aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081a4d30>, '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 0x1080cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108199be8>, '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.kMd4P7D7jn/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.5s1tQuJeH2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:02:09 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 11:02:09 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 11:02:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kMd4P7D7jn/Render', 11:02:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:02:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:02:09 INFO - 'HOME': '/Users/cltbld', 11:02:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:02:09 INFO - 'LOGNAME': 'cltbld', 11:02:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:09 INFO - 'MOZ_NO_REMOTE': '1', 11:02:09 INFO - 'NO_EM_RESTART': '1', 11:02:09 INFO - 'PAGER': '/bin/cat', 11:02:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:02:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:09 INFO - 'PWD': '/builds/slave/test', 11:02:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:02:09 INFO - 'SHELL': '/bin/bash', 11:02:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5s1tQuJeH2/Listeners', 11:02:09 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 11:02:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:02:09 INFO - 'USER': 'cltbld', 11:02:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:02:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:02:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:09 INFO - 'XPC_FLAGS': '0x0', 11:02:09 INFO - 'XPC_SERVICE_NAME': '0', 11:02:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:02:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:02:09 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-xZxOiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:02:10 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:02:10 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-51YmYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:02:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:02:10 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-Xlgqau-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:02:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:02:10 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-czLb7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:02:10 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)) 11:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:02:10 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-o7XEjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:02:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:02:10 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-alxPXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:02:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:02:10 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-jaXvcJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:02:10 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)) 11:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:02:10 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-R6DBvK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:02:10 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)) 11:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:02:10 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-skDcQE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:02:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:02:10 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-chuDZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:02:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:02:11 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-st5HmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:02:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:02:11 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-qszPFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:02:11 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)) 11:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:02:11 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-mDFhAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:02:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:02:11 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-aK4WY8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:02:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:02:11 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-7BTa0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:02:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:02:11 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-K_Zaki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:02:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:02:11 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-PlwnvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:02:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:02:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:02:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:02:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:02:11 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)) 11:02:11 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)) 11:02:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:02:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:11 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 11:02:11 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 11:02:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:14 INFO - Downloading blessings-1.6.tar.gz 11:02:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:02:14 INFO - Installing collected packages: blessings 11:02:14 INFO - Running setup.py install for blessings 11:02:14 INFO - Successfully installed blessings 11:02:14 INFO - Cleaning up... 11:02:14 INFO - Return code: 0 11:02:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:02:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:02:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:02:15 INFO - Reading from file tmpfile_stdout 11:02:15 INFO - Current package versions: 11:02:15 INFO - blessings == 1.6 11:02:15 INFO - blobuploader == 1.2.4 11:02:15 INFO - docopt == 0.6.1 11:02:15 INFO - functools32 == 3.2.3-2 11:02:15 INFO - jsonschema == 2.5.1 11:02:15 INFO - manifestparser == 1.1 11:02:15 INFO - mozInstall == 1.12 11:02:15 INFO - mozcrash == 1.0 11:02:15 INFO - mozdebug == 0.1 11:02:15 INFO - mozdevice == 0.49 11:02:15 INFO - mozfile == 1.2 11:02:15 INFO - mozhttpd == 0.7 11:02:15 INFO - mozinfo == 0.9 11:02:15 INFO - mozleak == 0.1 11:02:15 INFO - mozlog == 3.4 11:02:15 INFO - moznetwork == 0.27 11:02:15 INFO - mozprocess == 0.24 11:02:15 INFO - mozprofile == 0.28 11:02:15 INFO - mozrunner == 6.13 11:02:15 INFO - mozscreenshot == 0.1 11:02:15 INFO - mozsystemmonitor == 0.3 11:02:15 INFO - moztest == 0.8 11:02:15 INFO - mozversion == 1.4 11:02:15 INFO - psutil == 3.1.1 11:02:15 INFO - requests == 1.2.3 11:02:15 INFO - wsgiref == 0.1.2 11:02:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:02:15 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')]} 11:02:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:15 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')]} 11:02:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:15 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 0x1083c6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083c9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85ebde2aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081a4d30>, '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 0x1080cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108199be8>, '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.kMd4P7D7jn/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.5s1tQuJeH2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:02:15 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 11:02:15 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 11:02:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kMd4P7D7jn/Render', 11:02:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:02:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:02:15 INFO - 'HOME': '/Users/cltbld', 11:02:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:02:15 INFO - 'LOGNAME': 'cltbld', 11:02:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:15 INFO - 'MOZ_NO_REMOTE': '1', 11:02:15 INFO - 'NO_EM_RESTART': '1', 11:02:15 INFO - 'PAGER': '/bin/cat', 11:02:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:02:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:15 INFO - 'PWD': '/builds/slave/test', 11:02:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:02:15 INFO - 'SHELL': '/bin/bash', 11:02:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5s1tQuJeH2/Listeners', 11:02:15 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 11:02:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:02:15 INFO - 'USER': 'cltbld', 11:02:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:02:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:02:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:15 INFO - 'XPC_FLAGS': '0x0', 11:02:15 INFO - 'XPC_SERVICE_NAME': '0', 11:02:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:02:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:02:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:02:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:02:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:02:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:02:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:02:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:02:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:02:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:02:15 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:02:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:02:15 INFO - Cleaning up... 11:02:15 INFO - Return code: 0 11:02:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:02:15 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')]} 11:02:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:15 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')]} 11:02:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:15 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 0x1083c6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083c9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85ebde2aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081a4d30>, '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 0x1080cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108199be8>, '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.kMd4P7D7jn/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.5s1tQuJeH2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:02:15 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 11:02:15 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 11:02:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kMd4P7D7jn/Render', 11:02:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:02:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:02:15 INFO - 'HOME': '/Users/cltbld', 11:02:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:02:15 INFO - 'LOGNAME': 'cltbld', 11:02:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:15 INFO - 'MOZ_NO_REMOTE': '1', 11:02:15 INFO - 'NO_EM_RESTART': '1', 11:02:15 INFO - 'PAGER': '/bin/cat', 11:02:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:02:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:15 INFO - 'PWD': '/builds/slave/test', 11:02:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:02:15 INFO - 'SHELL': '/bin/bash', 11:02:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5s1tQuJeH2/Listeners', 11:02:15 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 11:02:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:02:15 INFO - 'USER': 'cltbld', 11:02:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:02:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:02:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:15 INFO - 'XPC_FLAGS': '0x0', 11:02:15 INFO - 'XPC_SERVICE_NAME': '0', 11:02:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:02:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:02:15 INFO - Cleaning up... 11:02:15 INFO - Return code: 0 11:02:15 INFO - Running post-action listener: _resource_record_post_action 11:02:15 INFO - Running post-action listener: _start_resource_monitoring 11:02:15 INFO - Starting resource monitoring. 11:02:15 INFO - [mozharness: 2017-03-01 19:02:15.711885Z] Finished create-virtualenv step (success) 11:02:15 INFO - [mozharness: 2017-03-01 19:02:15.712816Z] Running install step. 11:02:15 INFO - Running pre-action listener: _resource_record_pre_action 11:02:15 INFO - Running main action method: install 11:02:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:02:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:02:15 INFO - Reading from file tmpfile_stdout 11:02:15 INFO - Detecting whether we're running mozinstall >=1.0... 11:02:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:02:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:02:16 INFO - Reading from file tmpfile_stdout 11:02:16 INFO - Output received: 11:02:16 INFO - Usage: mozinstall [options] installer 11:02:16 INFO - Options: 11:02:16 INFO - -h, --help show this help message and exit 11:02:16 INFO - -d DEST, --destination=DEST 11:02:16 INFO - Directory to install application into. [default: 11:02:16 INFO - "/builds/slave/test"] 11:02:16 INFO - --app=APP Application being installed. [default: firefox] 11:02:16 INFO - mkdir: /builds/slave/test/build/application 11:02:16 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'] 11:02:16 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 11:02:38 INFO - Reading from file tmpfile_stdout 11:02:38 INFO - Output received: 11:02:38 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:02:38 INFO - Running post-action listener: _resource_record_post_action 11:02:38 INFO - [mozharness: 2017-03-01 19:02:38.453988Z] Finished install step (success) 11:02:38 INFO - [mozharness: 2017-03-01 19:02:38.454119Z] Running run-tests step. 11:02:38 INFO - Running pre-action listener: _resource_record_pre_action 11:02:38 INFO - Running main action method: run_tests 11:02:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:02:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:02:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:02:38 INFO - 2017-03-01 11:02:38.468 screenresolution[1627:7324] starting screenresolution argv=screenresolution get 11:02:38 INFO - 2017-03-01 11:02:38.471 screenresolution[1627:7324] Display 0: 1600x1200x32@60 11:02:38 INFO - 2017-03-01 11:02:38.477 screenresolution[1628:7326] starting screenresolution argv=screenresolution list 11:02:38 INFO - Available Modes on Display 0 11:02:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:02:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:02:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:02:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:02:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:02:38 INFO - Intel Iris: 11:02:38 INFO - Chipset Model: Intel Iris 11:02:38 INFO - Type: GPU 11:02:38 INFO - Bus: Built-In 11:02:38 INFO - VRAM (Dynamic, Max): 1536 MB 11:02:38 INFO - Vendor: Intel (0x8086) 11:02:38 INFO - Device ID: 0x0a2e 11:02:38 INFO - Revision ID: 0x0009 11:02:38 INFO - Displays: 11:02:38 INFO - Display: 11:02:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:02:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:02:38 INFO - Display Serial Number: 1600x1200 60 11:02:38 INFO - Main Display: Yes 11:02:38 INFO - Mirror: Off 11:02:38 INFO - Online: Yes 11:02:38 INFO - Rotation: Supported 11:02:38 INFO - Return code: 0 11:02:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:02:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:02:38 INFO - Python 2.7.3 11:02:38 INFO - Return code: 0 11:02:38 INFO - grabbing minidump binary from tooltool 11:02:38 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')]} 11:02:38 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 0x1081a4d30>, '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 0x1080cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108199be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:02:38 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:02:38 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 11:02:38 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:02:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:02:38 INFO - Return code: 0 11:02:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:02:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:02:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:02:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:02:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:02:38 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-0082', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 11:02:38 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-0082 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 11:02:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kMd4P7D7jn/Render', 11:02:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:02:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:02:38 INFO - 'HOME': '/Users/cltbld', 11:02:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:02:38 INFO - 'LOGNAME': 'cltbld', 11:02:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:02:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:02:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:38 INFO - 'MOZ_NO_REMOTE': '1', 11:02:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:02:38 INFO - 'NO_EM_RESTART': '1', 11:02:38 INFO - 'PAGER': '/bin/cat', 11:02:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:02:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:38 INFO - 'PWD': '/builds/slave/test', 11:02:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:02:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:02:38 INFO - 'SHELL': '/bin/bash', 11:02:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5s1tQuJeH2/Listeners', 11:02:38 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 11:02:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:02:38 INFO - 'USER': 'cltbld', 11:02:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:02:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:02:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:38 INFO - 'XPC_FLAGS': '0x0', 11:02:38 INFO - 'XPC_SERVICE_NAME': '0', 11:02:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:02:38 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-0082', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 11:02:39 INFO - mozversion application_buildid: 20170214071445 11:02:39 INFO - mozversion application_changeset: fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd 11:02:39 INFO - mozversion application_display_name: Nightly 11:02:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:02:39 INFO - mozversion application_name: Firefox 11:02:39 INFO - mozversion application_remotingname: firefox 11:02:39 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:02:39 INFO - mozversion application_vendor: Mozilla 11:02:39 INFO - mozversion application_version: 54.0a1 11:02:39 INFO - mozversion platform_buildid: 20170214071445 11:02:39 INFO - mozversion platform_changeset: fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd 11:02:39 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:02:39 INFO - mozversion platform_version: 54.0a1 11:02:39 INFO - using testdate: 1488394959 11:02:39 INFO - actual date: 1488394959 11:02:39 INFO - starting webserver on 'localhost:49230' 11:02:39 INFO - SUITE-START | Running 2 tests 11:02:39 INFO - TEST-START | dromaeo_css 11:02:39 INFO - operating with platform_type : mac_ 11:02:39 INFO - Initialising browser for dromaeo_css test... 11:02:39 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpm3nk8O/profile http://localhost:49230/getInfo.html) 11:02:41 INFO - PROCESS | 1638 | 2017-03-01 11:02:41.986 plugin-container[1640:7493] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 11:02:41 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:02:41 INFO - PROCESS | 1638 | 2017-03-01 11:02:41.989 plugin-container[1640:7493] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 11:02:41 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:02:41 INFO - PROCESS | 1638 | 2017-03-01 11:02:41.989 plugin-container[1640:7493] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:02:42 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 11:02:42 INFO - PROCESS | 1638 | colorDepth:24 11:02:42 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 11:02:42 INFO - PROCESS | 1638 | __metrics 11:02:42 ERROR - PROCESS | 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 11:02:42 INFO - TEST-INFO | 1638: exit 0 11:02:42 INFO - Browser initialized. 11:02:42 INFO - Running cycle 1/1 for dromaeo_css test... 11:02:42 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpm3nk8O/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:02:45 INFO - PROCESS | 1641 | 2017-03-01 11:02:45.301 plugin-container[1642:7616] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 11:02:45 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:02:45 INFO - PROCESS | 1641 | 2017-03-01 11:02:45.302 plugin-container[1642:7616] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4423, name = 'com.apple.CFPasteboardClient' 11:02:45 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:02:45 INFO - PROCESS | 1641 | 2017-03-01 11:02:45.302 plugin-container[1642:7616] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:02:55 INFO - PROCESS | 1641 | RSS: Main: 234401792 11:02:55 INFO - PROCESS | 1641 | 11:05:58 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49230/tests/dromaeo/cssquery-ext.html) 11:06:00 INFO - PROCESS | 1641 | RSS: Main: 255676416 11:06:00 INFO - PROCESS | 1641 | 11:07:43 INFO - PROCESS | 1641 | ************************* 11:07:43 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 11:07:43 INFO - PROCESS | 1641 | 11:07:43 ERROR - PROCESS | 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 11:07:43 INFO - PROCESS | 1641 | Full stack: 11:07:43 INFO - PROCESS | 1641 | ************************* 11:09:02 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-ext.html (next: http://localhost:49230/tests/dromaeo/cssquery-jquery.html) 11:09:03 INFO - PROCESS | 1641 | RSS: Main: 262295552 11:09:03 INFO - PROCESS | 1641 | 11:12:05 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49230/tests/dromaeo/cssquery-mootools.html) 11:12:06 INFO - PROCESS | 1641 | RSS: Main: 272621568 11:12:06 INFO - PROCESS | 1641 | 11:15:08 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49230/tests/dromaeo/cssquery-prototype.html) 11:15:08 INFO - PROCESS | 1641 | RSS: Main: 264704000 11:15:08 INFO - PROCESS | 1641 | 11:18:11 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49230/tests/dromaeo/cssquery-yui.html) 11:18:12 INFO - PROCESS | 1641 | RSS: Main: 268812288 11:18:12 INFO - PROCESS | 1641 | 11:21:15 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-yui.html (next: http://localhost:49230/tests/dromaeo/cssquery-dojo.html) 11:21:15 INFO - PROCESS | 1641 | RSS: Main: 270422016 11:21:15 INFO - PROCESS | 1641 | 11:21:15 INFO - PROCESS | 1641 | __start_tp_report 11:21:15 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 11:21:15 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 11:21:15 INFO - PROCESS | 1641 | |i|pagename|runs| 11:21:15 INFO - PROCESS | 1641 | |0;dojo.html;3367.7474189435784;3380.323935212957;3395.9320813583727;3403.2342722887347;3425.7773244739105;5601.971990140054;5627.9718601406985;5692.174634677975;5702.971485142573;5744.080947048473;5599.216109744639;5602.13166959121;5644.209810626506;5689.9146512802345;5789.652620842742;5089.643724939256;5095.261187127864;5097.5667068299235;5179.663321884084;5213.739313034352;5162.174052151657;5214.0875346814155;5218.973905130469;5247.921281180786;5250.7112108834;5042.621803364762;5080.288759573663;5117.769700363493;5150.124478838607;5234.895302093937;5125.282460455539;5152.896942061137;5153.845384638467;5160.432352441228;5193.844184674465;4984.77568509418;5112.41207261166;5150.896982060375;5156.561692256163;5173.922391164136;4358.825646974117;4980.7509624518625;5239.449857764918;5389.8113566025395;5405.459454054602;4832.492588278215;5955.642661440327;5956.076808094752;5963.0459126539545;5971.940280597182;6032.517398608101;6047.667378294192;6051.940910340717;6061.333253342129;6080.665563394012;5549.6115271930985;5607.551395888319;5647.209390685307;5651.6043876928225;5705.429457054344;5392.840539284073;6135.355158799479;6168.784092556739;6222.097795819581;6237.75048998035;4803.255495398149;5827.475527202572;6081.118237855575;6084.209052823193;6132.877342453126;5172.7155006475;6247.75008999644;6250.4374606285655;6255.655938923403;6278.340774218641;5975.940240597538;6057.3336932937345;6221.906671399933;6222.3466536013975;6232.25212974446;4738.3129446230105;5601.775928962796;6104.06507294887;6129.970128114947;6143.354947730513;4824.0834241494;4929.112759703216;4938.950610493921;4959.702417854868;4975.751212439363;3670.4127339626066;4423.093265880487;4430.778461076933;4431.534688857688;4460.330950357472;5602.159676048625;5634.915476267859;5709.029464990961;5722.685252311079;5855.326637436664;6209.565330426917;6240.687965601702;6243.875122497616;6246.312905580383;6293.811185664437;5886.676232807151;5889.293284805851;5926.436988486087;5926.703664816828;5968.373320801254;5273.947260527422;5313.256144139824;5326.094563924142;5455.9633669602645;5487.368952570426;6169.198004259597;6195.938040619536;6203.16257305276;6229.470495007975;6338.683065846782;6185.443310102112;6232.937670623417;6233.532485063548;6244;6256.468200202851;6266.530010249158;6317.368263173829;6321.905171422524;6338.112664227104;6338.461230795434;5827.329857066492;5831.650100993954;5876.059830427112;5978.641281523122;6082.2093127893095;6004.369541198112;6209.96895015522;6220.968895155676;6229.937700622936;6234.688265586793;5697.116946873241;5808.738606762621;6023.45788879003;6062.090686397076;6104.389561044038;5041.571466425395;6117.553376797925;6122.245686735438;6137.285055221507;6146.893559159215;5689.573282003949;5716.885662286814;5956.017257152695;5968;5985.5810093294795;6077.6353418795015;6096.451319381277;6119.837693858789;6124.418180272867;6126.30393285589;5969.074793407029;5993.340732519507;5997.850053748692;6026.035834266497;6027.457528822427 11:21:15 INFO - PROCESS | 1641 | |1;ext.html;467.3480494709882;477.9259214821704;488.99511503859554;490.13980464285174;511.2254933775331;37958.05104872376;40110;40275;40288.395674064916;40307.39538906911;41263.79368103163;41719;41824;41849.790751046276;41867.58132418675;7411.2959268869645;9108.59011344491;9337.486438245895;9345.532723363805;9368.063193680646;7562.243775622449;11001.504932278067;11240.100791936666;11245.381504017316;11422.942885285562;11423.371714555697;11525.423728813526;11552.191346605741;11589.594364197255;11601.825972610417;11529.654110376701;11547.364894930775;11598.76802463946;11615.883841161565;11621.360825154614;11000.889991100066;11219.326840389604;11285.887141128565;11297.661070167907;11311.604093856675;27280.181594552196;38434.80782596069;40305.79847100775;40388.59611403907;40397;3256.625488068879;3786.5198295116465;3818.6372294632242;3839.884803455901;3960.5049368828895;23518;29752;29921.850390748124;30710.078697638884;30961.845190774126;2785.7214278572183;2801.86524457595;3030.8939187128344;3054.77808876449;3104.9689503104905;424684;425730;427564;431867;431879;48626.5137348622;49905.50094499081;51756.482435175916;60254;60464.395356046756;62862;63552.36447635465;64262.03606945806;67694.3230567688;68073.65963170251;13726.039177257695;13799.310034498234;13875.37560809766;13886.4445422184;13908.37412316428;10368.066873981377;10518.158547316196;10593.947030264799;10607.204459665556;10642.56325396077;13253.403596838165;16400.933939293907;16489.91755041217;16564.254608542644;16662.50012499627;6564.7374105036215;6609.339066093448;6622;6670.566413183127;6682.632455214913;739.3530660671912;806.6289506826809;816.6814942172557;833.6082041440513;858.9184027517376;23898.88050559736;25786.742132578434;27296.908123675228;28375.858120709265;28761;1018.607835983137;1034.7723500829804;1052.315994603514;1053.6154303679102;1162.8546431696038;12578.81131783024;13549.864501355058;15074.698506030038;15456.845431545766;15604.68790624204;481737;630964;633519;767517;767967;52488.900443981816;52808;53009.939801203756;53150;53486;1287.2791236907362;1320.7820709583004;1349.4399824073096;1351.9526816561372;1358.8437329707012;27720.722792771816;27768;28033.79493076055;28265.434691305647;28470.715292846806;43621.781891090344;44493.55506444894;44743.88140296388;44854.3271850929;44987.55012449834;378717;453222;455140;456293;456513;16632;18873.05634718231;21583.784162158176;24166.275011749676;24376;41057.589424105376;41296.587034129276;41359.793201033805;41452.79273603613;42779.78610107054;33515;34243.31513369669;34333;34508.65491345055;35226.64773352234;34115.65884341125;34208.82895585506;34792;35130.64869351274;35198.824005879804;33643;34612.134696632784;34615.65384346225;34621;34665.960021199404;28386.85806570954;30574;30792.076237712903;30932.690673093883;31278;20370.89814550977;22340.77659223452;22980.885095575082;23166.957486912794;23191.420214494774 11:21:15 INFO - PROCESS | 1641 | |2;jquery.html;377.72426128925866;379.3351704277449;394.4360610180634;396.1206122408254;413.6463323858101;12855.421506032228;14284.500042498514;19619.5095122622;20858.687119693215;20930.16279348826;20577;21614.675779863315;21799.564008719815;22227.110915563357;22492.662610060863;4573;5408;5653.20855080287;5717.514011309044;5887.322957859858;4826.734529600871;5879.911801322984;5887.646741195542;5890.764369425217;5901.321348044966;3631.4734363517273;6357.777477788279;6499.38255865692;6577.539572229945;6600.80197594073;5610.990021796075;6143.354947730513;6533.44465720412;6575;6617.239017512999;4879.316895634614;5663.235463212455;5674.9716251418895;5702.600817942745;5716.57125715573;22667.54664906709;22835.657465138036;25966.480670386485;26359.472810543873;26533.805978730812;2387.6657267982323;2563.3976015636313;2636.657234559514;2765.170448865332;2782.5687018512162;9905.851412228822;10273.897261027369;10310.536025878853;12249.203801752858;14313.42746290147;1738.6435780664933;1785.2680401035514;1789.159095225242;1799.8200179982027;1822.334847780564;87768.12231877727;107550;113648.4317578415;114634;124735;11440;11825.940870295766;11845.111616628787;12019.879801201876;12180.208286461351;15208.098987626547;15706;15721.371145154295;15726.92136539333;15788.526344209693;7619.860936567497;7890.250426209501;7898;8012.679492820338;8017.639206235734;7167.569945803269;7169.533980291264;7408.518446301081;7423.44324175689;7433.776986690408;4088.7733679895914;4173.332266837291;4767.165745994437;4776.808927642855;4828.155072862235;1968.635802376565;2056.270024141438;2084;2085.48905518149;2092.393205970282;979.8040391921644;989.9158571521357;1003.4781913404988;1008.1632245236436;1042.3902017319954;21297.574048519255;21554.784452155593;21613.135474581155;21687.240946566795;21696.457588560414;1006.8187726209353;1016.6289304403839;1025.8512515685184;1049.506731836036;1055.519738518972;9162.725118246462;11016.669499915015;11268.605598804003;11335.829962550568;11350.375729334966;543698;616461;705478;713152;713176;97672.04655906842;113197;140565.3773849055;143339.2833035828;146052;1008.909198172168;1043.1446214104362;1081.4268437728017;1081.9729506762394;1087.1574529739357;16671.16590179648;19402.0298985053;19462.8053719461;19512.902435487733;19819.335037344037;54025.81896873305;55743.44256557382;56184.7295196288;56198.8760224785;57058.7147064262;53474.73262633662;78803.60598196973;82732;83155.16844831474;84742.88142796479;8102;8222.794430139294;8231.547264900488;8494.872576911228;8508.234258916727;11585;12053;12343.506259749509;12476.18904771187;12778.361081945679;10097.293189476666;10740.194485413469;10848.45757712127;10878.782424351626;10934.726631834268;8959.41763785352;9187.540622968958;9544.379615324982;10134.645287414905;10414.010668986433;10881.891181088087;11058;11119.888801111885;11236.381998990135;11512.769744605228;7278.636068196463;8027.678892844221;8151.184881511801;8203.671853126047;8571.95714021426;10689.893101069201;10748.193885458466;10886.727831803952;10901.618443354444;10941.945290273497 11:21:15 INFO - PROCESS | 1641 | |3;mootools.html;1436.9856301436982;1443.2350854047356;1443.5236371997235;1451.4411951398708;1484.2949598940504;7417.740379086733;8260.256576908077;8443.691227859676;8682.348823838214;8713.390062695615;8475.57622118893;8529.402941794076;8637.568121593926;8647.91352086479;8656.91343086569;2273.3520946530284;2310.318456055468;2314.5023819878725;2316.664083707861;2323.6282194848827;2278.840481166319;2299.666548350488;2310.90756369745;2311.087120587367;2323.2913961241816;2247.763984781591;2291.255342013844;2293.117149897285;2310.87290199039;2311.3528212100637;2043.5402034542183;2182.879941603211;2261.604219261623;2275.3174047785683;2302.7466978632337;2249.460129568908;2297.2533926473798;2315.0161181497797;2317.154238702861;2322.7677232276637;8516.70191543293;8633.784155396103;8692.826143477096;8706.695265665734;8716.084811094806;5139.820106296299;5209.531142197183;5258.13240815265;5292.470752924715;5339.225812257239;3257.201985513541;3355.312161006988;3485.790852548855;3490.4415293553166;3521.5950165731015;1183.5088438298096;1208.746163305707;1216.2945491614842;1228.8279640850199;1242.43469221504;8751.912480875173;9713.90286097137;10055.095041446304;10778.191635627281;10803.4058126803;4331.4152589400655;4373.453318335208;4534.659900507475;4550.590446859798;4555.2711566149255;8816.206541411304;8830.293576513863;8851.424657397378;8857.070007649232;8858.689945851864;5052.141136006888;5107.361579802525;5139.948600514022;5141.974290128525;5153.484651534892;4575.519570445122;4754.643401744814;4795.520447955177;4798;4810.542998415145;3631.2555926035097;3643.795503720264;3660.396034654252;3703.129764505339;3718.7356298858517;1139.5783560082657;1144.7939370913316;1151.7120719820045;1161.96514104577;1163.8196079607674;735.7424901284614;834.3700506117842;896.8161526886972;909.5225006871445;929.0802105915095;4924.43369012561;5439.700816455053;5468.125099984063;5692.743826527816;5810.88378232433;862.4523427623504;882.955852207387;986.3736527305213;993.4536005197112;1014.1379827146866;3667.853285868589;3772.5661548921676;3814.675752561008;3864.4976153099915;3903.2583809076186;7290;9463.769709937664;9652.710418687451;10001.949990250003;10035.197184225244;9818.361806482555;9968.202543796478;10036.899631003742;10058.849117263248;10060.69817905464;6854.931450685529;7513.2111128331835;7898.170692077364;7924.326432253208;8025.317847982987;4969.781056140807;5094.719790411563;5456.181572764117;5873.089671100986;5947.732352044255;7361.411087113125;7623.809404764926;7959.522428654299;8757.817694611145;9462.905370946477;9133.223675987616;9404.341696081208;9549.474778887228;9557.569909354235;9566.330356874952;7197.316254955771;7265.237150099375;7284.344409003003;7309.8903516446235;7324.157721861841;7879.763607091796;7943.602819859099;7964.402669799904;7971.601419929097;7972.083210430643;7164.283571642794;7208.243134471015;7266.818329541804;7320.743773968099;7400.074990626172;7077.079979602619;7092.787216383516;7111.822204444723;7125.1093613298335;7148.964255178691;6088.634681919099;6311.842203944754;6411.8717625646295;6424.38968298011;6429.357064293533;4912.140375434356;4934.235193545006;5006.224035274538;5056.418511871182;5079.568236699922;3505.5793304803587;4286.785660717014;4454.777261136995;4600.42494688164;4730.503297153887 11:21:15 INFO - PROCESS | 1641 | |4;prototype.html;350.4095598915827;359.0986623574826;362.65910044558115;363.04338069187696;371.7676452217364;4964.135147555397;5052.6210534209895;5240.6898275431095;5420.566354691625;5503.91744123838;4437.889052773682;4490.4835943866465;5383.3849845046425;5428.4300148484435;5520.641158324716;3931.783751893645;4044.9191016179657;4088.080181959065;4096.201240758053;4107.650849677766;3422.5379573757473;4087.20299541588;4091.8288326546867;4101.138760860208;4104.733192342503;4071.5724848890886;4083.3466645336603;4086.4891888513935;4100.323446631317;4118.546959834416;2923.795334326598;3687.8893633191046;4003.3794761811964;4096.774677392742;4134.524529679095;3186.267158553546;3466.4107101792756;3487.4541584643353;3494.5107684924133;3502.824858757052;4378.387025816388;4378.555765897617;4585.931211031837;5274.05067087924;5297.417284098746;3702.981485092584;4404.339349097629;4421.093675796486;4676.532346765331;4715.292706094079;5234.33049144935;5507.862303442406;5699.629524080921;5713.286363863481;5748.626339287975;3508.3684936711384;3749.887503374903;3815.8361699681586;3893.844246230176;3904.1606054698377;4514.977425112854;5060.418051924003;5669.9433005669825;5872.471477567039;6083.574149809515;3110.98444507776;3312.536244925664;3388.3053973935284;3762.247550489913;3795.582485926546;3999.6000399960353;4000.8999775005277;4010.438538604598;4019.4975628046495;4027.234825383168;4469;4660.067986402733;4666.813327466931;4668.5564871337165;4686.531346865286;4741.620670346433;4790.401199850019;5516.862078448071;5769.88460230793;5792.710364481759;1144.4035570379274;1152.467218599261;1153.961434708752;1261.4449642157422;1272.9045321600918;826.2852632472988;848;858.0304256190465;879.2438502887512;883.4301875290402;4487.932681009787;4545.886257866844;4569.908601827945;4955.603551715854;5143.116946705213;5097.745112744348;5207.401148867929;5458.536024437888;5605.962896864094;5618.381977982419;4476.977615111904;4698.859034228979;4896.586280628464;4964.975175124101;5122;2873.5545990371525;2961.851907404621;2978.717021882918;2996.4456575533595;3002.309468822183;5319.962607291541;5561;5829.941700583025;5881.3530511643685;5952.523798096142;5165.974170129125;5496.835094947158;5625.5092400513895;5753.583494932055;5834.416558344216;4872.537108974641;5023.723695196799;5087.542121209109;5554.916791225673;5667.008273552112;4849.442314133919;5911.3201981772645;5938.851528711817;5967.791127310524;6018.307894592178;5013.423456302572;5502.697351645698;5730.942690573208;5792.652440853562;5834.883302334015;4972.477889821516;5890.49515538254;6131.478824299985;6193.504519638508;6239.4713295242955;3886.378179491268;4216.79821250942;4251.234777740036;4285.799976006723;4300.182965236595;5229.320188375486;5554.416786237387;5796.391378905154;5837.591368604327;5910.468057874812;5771.7402716877;5774.351646924266;5781.8554536136935;5810.73851676684;5915.1551491530845;4617.799372163195;4695.5304469552775;5535.446455354432;5743.8564035899435;5791.218185545066;4452.710573812692;5032.3961124665275;5590.385057643574;5618.690971996416;5649.774009039593;4672.976635116803;5778.913316300175;5803.187553742395;5852.483432124057;5872.941270587239;4830.7826147822725;5004.899902002013;5011.724355160355;5668.234788303691;5698.601097923106 11:21:15 INFO - PROCESS | 1641 | |5;yui.html;672.5762769455242;691.9238883722793;731.9597422141782;751.5791156952107;858.5278097046624;14020.439182432703;14438.639034024154;14461.493847715332;14477.565673029812;14503.98472106953;14368.281585920715;14431.783523247159;14552.636184095403;14569.27153642319;14577.05249158804;552.7512619321301;887.8490656588364;898.285862739124;905.814308066848;906.5875026862761;657.4871600151865;761.889526018727;897.9865302020474;923.487464457226;925.1673493855517;929.0848514213494;952.4047470331043;953.5232383808096;954.8901876284212;957.0716405087054;809.2069771623783;930.5579849571467;943.9386439881386;953.4660590069549;953.8378475659155;606.9514438844882;681.6966449929783;701.1586096684;703.5919166883195;703.7642389799423;15074.170920599363;15311.234438278152;15352.846471535366;15377.461788837449;15542.067475951479;14126.858731412656;14248.446897507652;14313.78529322061;14344.56966291013;14651.560453186421;10797.83803242952;10962.342259464458;10965.50655220517;11034.393108379036;11353.489092990836;3629.800360980145;3660.5790334111653;3794.1273507093247;3798.1264309209037;3806.619338066199;77383.22616773816;90874;94299.52850235705;94553.52723236408;95716;9575.377600455948;9940.45327506994;10717.678469645924;10766.730831729114;10940.288881222854;21434.464138396354;21470.355889323346;21802.345929622137;21958.45103872384;21978.780212197995;7180.60506672138;7400.814979625552;7405.5926924019695;7451.125046108503;7627.084749830056;4074.0425999890003;4118.979405102956;4125.608067233608;4134.689898257643;4140.896477588084;3468.3930312195266;3469.8438570264398;3525.347810655037;3538.15084379747;3570.571531416195;660.8744338575656;661.5633681770008;666.0941120076692;679.5582871133704;682.1745687717818;602.8312072619674;609.58852774378;623.1867413025954;635.4344633276388;653.1867824558456;7471.066116735408;8518.105598912149;8862.601182946783;9512.524373781422;9561.330706850593;654.4802510335267;656.6920998251486;660.3099760750027;660.3924389561615;673.3905815237218;4005.2990726622725;4415.7517113284985;4453.415194179828;4606.101810146989;4698.765061746899;92378.53810730904;95558;96066.0393396057;97429;97850.5107474458;84837;85374.71937920936;85854;85964.28071438661;87611.56194218989;60675.786484270066;72272.55454890872;75632.62183689047;77683.83474248004;78484.21515784883;8831.867521987304;8847.071057538866;8867.778305542413;8898.555072246232;9038.548072596475;35071;40355.78043207534;44285.77857110694;45738.31392529048;46783;56353;56654;64291.67854160699;66007.66996164988;66294.66852665706;10062.34594751368;12242.144681695523;12598.874011260137;12611.621651350475;12748.980081593265;13873.79189312141;13926.58220253394;14179.64550886236;14653;14759.262036898326;12942.482300707867;12984.285864277555;13010.869891301345;13177.670558236121;13747.243901585109;12958.935205323913;13035;13264.86735132675;13307.06850520493;13564.457421703022;12782.296973666538;12869.099163058496;12916;12997.15518491295;13547.322633868464;10648.89351106479;10816.837747433952;10825.56697732082;10852.403117828595;10866.293690910066;9489.098535639103;9700.951495242478;10645.574177032831;10662.360258384522;10678.57285708563 11:21:15 INFO - PROCESS | 1641 | __end_tp_report 11:21:15 INFO - PROCESS | 1641 | __start_cc_report 11:21:15 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,424 11:21:15 INFO - PROCESS | 1641 | __end_cc_report 11:21:15 INFO - PROCESS | 1641 | __startTimestamp1488396075855__endTimestamp 11:21:15 INFO - PROCESS | 1641 | 11:21:15 INFO - PROCESS | 1641 | ------- Summary: start ------- 11:21:15 INFO - PROCESS | 1641 | Number of tests: 6 11:21:15 INFO - PROCESS | 1641 | 11:21:15 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5632.69 Median:5831.65 stddev:651.07 (11.2%) stddev-sans-first:628.36 11:21:15 INFO - PROCESS | 1641 | Values: 3367.7 3380.3 3395.9 3403.2 3425.8 5602.0 5628.0 5692.2 5703.0 5744.1 5599.2 5602.1 5644.2 5689.9 5789.7 5089.6 5095.3 5097.6 5179.7 5213.7 5162.2 5214.1 5219.0 5247.9 5250.7 5042.6 5080.3 5117.8 5150.1 5234.9 5125.3 5152.9 5153.8 5160.4 5193.8 4984.8 5112.4 5150.9 5156.6 5173.9 4358.8 4980.8 5239.4 5389.8 5405.5 4832.5 5955.6 5956.1 5963.0 5971.9 6032.5 6047.7 6051.9 6061.3 6080.7 5549.6 5607.6 5647.2 5651.6 5705.4 5392.8 6135.4 6168.8 6222.1 6237.8 4803.3 5827.5 6081.1 6084.2 6132.9 5172.7 6247.8 6250.4 6255.7 6278.3 5975.9 6057.3 6221.9 6222.3 6232.3 4738.3 5601.8 6104.1 6130.0 6143.4 4824.1 4929.1 4939.0 4959.7 4975.8 3670.4 4423.1 4430.8 4431.5 4460.3 5602.2 5634.9 5709.0 5722.7 5855.3 6209.6 6240.7 6243.9 6246.3 6293.8 5886.7 5889.3 5926.4 5926.7 5968.4 5273.9 5313.3 5326.1 5456.0 5487.4 6169.2 6195.9 6203.2 6229.5 6338.7 6185.4 6232.9 6233.5 6244.0 6256.5 6266.5 6317.4 6321.9 6338.1 6338.5 5827.3 5831.7 5876.1 5978.6 6082.2 6004.4 6210.0 6221.0 6229.9 6234.7 5697.1 5808.7 6023.5 6062.1 6104.4 5041.6 6117.6 6122.2 6137.3 6146.9 5689.6 5716.9 5956.0 5968.0 5985.6 6077.6 6096.5 6119.8 6124.4 6126.3 5969.1 5993.3 5997.9 6026.0 6027.5 11:21:15 INFO - PROCESS | 1641 | 11:21:15 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:63579.66 Median:34288.16 stddev:140061.05 (408.5%) stddev-sans-first:140373.80 11:21:15 INFO - PROCESS | 1641 | Values: 467.3 477.9 489.0 490.1 511.2 37958.1 40110.0 40275.0 40288.4 40307.4 41263.8 41719.0 41824.0 41849.8 41867.6 7411.3 9108.6 9337.5 9345.5 9368.1 7562.2 11001.5 11240.1 11245.4 11422.9 11423.4 11525.4 11552.2 11589.6 11601.8 11529.7 11547.4 11598.8 11615.9 11621.4 11000.9 11219.3 11285.9 11297.7 11311.6 27280.2 38434.8 40305.8 40388.6 40397.0 3256.6 3786.5 3818.6 3839.9 3960.5 23518.0 29752.0 29921.9 30710.1 30961.8 2785.7 2801.9 3030.9 3054.8 3105.0 424684.0 425730.0 427564.0 431867.0 431879.0 48626.5 49905.5 51756.5 60254.0 60464.4 62862.0 63552.4 64262.0 67694.3 68073.7 13726.0 13799.3 13875.4 13886.4 13908.4 10368.1 10518.2 10593.9 10607.2 10642.6 13253.4 16400.9 16489.9 16564.3 16662.5 6564.7 6609.3 6622.0 6670.6 6682.6 739.4 806.6 816.7 833.6 858.9 23898.9 25786.7 27296.9 28375.9 28761.0 1018.6 1034.8 1052.3 1053.6 1162.9 12578.8 13549.9 15074.7 15456.8 15604.7 481737.0 630964.0 633519.0 767517.0 767967.0 52488.9 52808.0 53009.9 53150.0 53486.0 1287.3 1320.8 1349.4 1352.0 1358.8 27720.7 27768.0 28033.8 28265.4 28470.7 43621.8 44493.6 44743.9 44854.3 44987.6 378717.0 453222.0 455140.0 456293.0 456513.0 16632.0 18873.1 21583.8 24166.3 24376.0 41057.6 41296.6 41359.8 41452.8 42779.8 33515.0 34243.3 34333.0 34508.7 35226.6 34115.7 34208.8 34792.0 35130.6 35198.8 33643.0 34612.1 34615.7 34621.0 34666.0 28386.9 30574.0 30792.1 30932.7 31278.0 20370.9 22340.8 22980.9 23167.0 23191.4 11:21:15 INFO - PROCESS | 1641 | 11:21:15 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:36465.91 Median:22580.10 stddev:109884.77 (486.6%) stddev-sans-first:110159.62 11:21:15 INFO - PROCESS | 1641 | Values: 377.7 379.3 394.4 396.1 413.6 12855.4 14284.5 19619.5 20858.7 20930.2 20577.0 21614.7 21799.6 22227.1 22492.7 4573.0 5408.0 5653.2 5717.5 5887.3 4826.7 5879.9 5887.6 5890.8 5901.3 3631.5 6357.8 6499.4 6577.5 6600.8 5611.0 6143.4 6533.4 6575.0 6617.2 4879.3 5663.2 5675.0 5702.6 5716.6 22667.5 22835.7 25966.5 26359.5 26533.8 2387.7 2563.4 2636.7 2765.2 2782.6 9905.9 10273.9 10310.5 12249.2 14313.4 1738.6 1785.3 1789.2 1799.8 1822.3 87768.1 107550.0 113648.4 114634.0 124735.0 11440.0 11825.9 11845.1 12019.9 12180.2 15208.1 15706.0 15721.4 15726.9 15788.5 7619.9 7890.3 7898.0 8012.7 8017.6 7167.6 7169.5 7408.5 7423.4 7433.8 4088.8 4173.3 4767.2 4776.8 4828.2 1968.6 2056.3 2084.0 2085.5 2092.4 979.8 989.9 1003.5 1008.2 1042.4 21297.6 21554.8 21613.1 21687.2 21696.5 1006.8 1016.6 1025.9 1049.5 1055.5 9162.7 11016.7 11268.6 11335.8 11350.4 543698.0 616461.0 705478.0 713152.0 713176.0 97672.0 113197.0 140565.4 143339.3 146052.0 1008.9 1043.1 1081.4 1082.0 1087.2 16671.2 19402.0 19462.8 19512.9 19819.3 54025.8 55743.4 56184.7 56198.9 57058.7 53474.7 78803.6 82732.0 83155.2 84742.9 8102.0 8222.8 8231.5 8494.9 8508.2 11585.0 12053.0 12343.5 12476.2 12778.4 10097.3 10740.2 10848.5 10878.8 10934.7 8959.4 9187.5 9544.4 10134.6 10414.0 10881.9 11058.0 11119.9 11236.4 11512.8 7278.6 8027.7 8151.2 8203.7 8572.0 10689.9 10748.2 10886.7 10901.6 10941.9 11:21:15 INFO - PROCESS | 1641 | 11:21:15 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:5353.33 Median:5140.96 stddev:2898.26 (56.4%) stddev-sans-first:2891.45 11:21:15 INFO - PROCESS | 1641 | Values: 1437.0 1443.2 1443.5 1451.4 1484.3 7417.7 8260.3 8443.7 8682.3 8713.4 8475.6 8529.4 8637.6 8647.9 8656.9 2273.4 2310.3 2314.5 2316.7 2323.6 2278.8 2299.7 2310.9 2311.1 2323.3 2247.8 2291.3 2293.1 2310.9 2311.4 2043.5 2182.9 2261.6 2275.3 2302.7 2249.5 2297.3 2315.0 2317.2 2322.8 8516.7 8633.8 8692.8 8706.7 8716.1 5139.8 5209.5 5258.1 5292.5 5339.2 3257.2 3355.3 3485.8 3490.4 3521.6 1183.5 1208.7 1216.3 1228.8 1242.4 8751.9 9713.9 10055.1 10778.2 10803.4 4331.4 4373.5 4534.7 4550.6 4555.3 8816.2 8830.3 8851.4 8857.1 8858.7 5052.1 5107.4 5139.9 5142.0 5153.5 4575.5 4754.6 4795.5 4798.0 4810.5 3631.3 3643.8 3660.4 3703.1 3718.7 1139.6 1144.8 1151.7 1162.0 1163.8 735.7 834.4 896.8 909.5 929.1 4924.4 5439.7 5468.1 5692.7 5810.9 862.5 883.0 986.4 993.5 1014.1 3667.9 3772.6 3814.7 3864.5 3903.3 7290.0 9463.8 9652.7 10001.9 10035.2 9818.4 9968.2 10036.9 10058.8 10060.7 6854.9 7513.2 7898.2 7924.3 8025.3 4969.8 5094.7 5456.2 5873.1 5947.7 7361.4 7623.8 7959.5 8757.8 9462.9 9133.2 9404.3 9549.5 9557.6 9566.3 7197.3 7265.2 7284.3 7309.9 7324.2 7879.8 7943.6 7964.4 7971.6 7972.1 7164.3 7208.2 7266.8 7320.7 7400.1 7077.1 7092.8 7111.8 7125.1 7149.0 6088.6 6311.8 6411.9 6424.4 6429.4 4912.1 4934.2 5006.2 5056.4 5079.6 3505.6 4286.8 4454.8 4600.4 4730.5 11:21:15 INFO - PROCESS | 1641 | 11:21:15 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4503.19 Median:4926.09 stddev:1395.69 (28.3%) stddev-sans-first:1364.35 11:21:15 INFO - PROCESS | 1641 | Values: 350.4 359.1 362.7 363.0 371.8 4964.1 5052.6 5240.7 5420.6 5503.9 4437.9 4490.5 5383.4 5428.4 5520.6 3931.8 4044.9 4088.1 4096.2 4107.7 3422.5 4087.2 4091.8 4101.1 4104.7 4071.6 4083.3 4086.5 4100.3 4118.5 2923.8 3687.9 4003.4 4096.8 4134.5 3186.3 3466.4 3487.5 3494.5 3502.8 4378.4 4378.6 4585.9 5274.1 5297.4 3703.0 4404.3 4421.1 4676.5 4715.3 5234.3 5507.9 5699.6 5713.3 5748.6 3508.4 3749.9 3815.8 3893.8 3904.2 4515.0 5060.4 5669.9 5872.5 6083.6 3111.0 3312.5 3388.3 3762.2 3795.6 3999.6 4000.9 4010.4 4019.5 4027.2 4469.0 4660.1 4666.8 4668.6 4686.5 4741.6 4790.4 5516.9 5769.9 5792.7 1144.4 1152.5 1154.0 1261.4 1272.9 826.3 848.0 858.0 879.2 883.4 4487.9 4545.9 4569.9 4955.6 5143.1 5097.7 5207.4 5458.5 5606.0 5618.4 4477.0 4698.9 4896.6 4965.0 5122.0 2873.6 2961.9 2978.7 2996.4 3002.3 5320.0 5561.0 5829.9 5881.4 5952.5 5166.0 5496.8 5625.5 5753.6 5834.4 4872.5 5023.7 5087.5 5554.9 5667.0 4849.4 5911.3 5938.9 5967.8 6018.3 5013.4 5502.7 5730.9 5792.7 5834.9 4972.5 5890.5 6131.5 6193.5 6239.5 3886.4 4216.8 4251.2 4285.8 4300.2 5229.3 5554.4 5796.4 5837.6 5910.5 5771.7 5774.4 5781.9 5810.7 5915.2 4617.8 4695.5 5535.4 5743.9 5791.2 4452.7 5032.4 5590.4 5618.7 5649.8 4673.0 5778.9 5803.2 5852.5 5872.9 4830.8 5004.9 5011.7 5668.2 5698.6 11:21:15 INFO - PROCESS | 1641 | 11:21:15 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:19054.29 Median:2625.76 stddev:26805.19 (1020.9%) stddev-sans-first:26844.85 11:21:15 INFO - PROCESS | 1641 | Values: 672.6 691.9 732.0 751.6 858.5 14020.4 14438.6 14461.5 14477.6 14504.0 14368.3 14431.8 14552.6 14569.3 14577.1 552.8 887.8 898.3 905.8 906.6 657.5 761.9 898.0 923.5 925.2 929.1 952.4 953.5 954.9 957.1 809.2 930.6 943.9 953.5 953.8 607.0 681.7 701.2 703.6 703.8 15074.2 15311.2 15352.8 15377.5 15542.1 14126.9 14248.4 14313.8 14344.6 14651.6 10797.8 10962.3 10965.5 11034.4 11353.5 3629.8 3660.6 3794.1 3798.1 3806.6 77383.2 90874.0 94299.5 94553.5 95716.0 9575.4 9940.5 10717.7 10766.7 10940.3 21434.5 21470.4 21802.3 21958.5 21978.8 7180.6 7400.8 7405.6 7451.1 7627.1 4074.0 4119.0 4125.6 4134.7 4140.9 3468.4 3469.8 3525.3 3538.2 3570.6 660.9 661.6 666.1 679.6 682.2 602.8 609.6 623.2 635.4 653.2 7471.1 8518.1 8862.6 9512.5 9561.3 654.5 656.7 660.3 660.4 673.4 4005.3 4415.8 4453.4 4606.1 4698.8 92378.5 95558.0 96066.0 97429.0 97850.5 84837.0 85374.7 85854.0 85964.3 87611.6 60675.8 72272.6 75632.6 77683.8 78484.2 8831.9 8847.1 8867.8 8898.6 9038.5 35071.0 40355.8 44285.8 45738.3 46783.0 56353.0 56654.0 64291.7 66007.7 66294.7 10062.3 12242.1 12598.9 12611.6 12749.0 13873.8 13926.6 14179.6 14653.0 14759.3 12942.5 12984.3 13010.9 13177.7 13747.2 12958.9 13035.0 13264.9 13307.1 13564.5 12782.3 12869.1 12916.0 12997.2 13547.3 10648.9 10816.8 10825.6 10852.4 10866.3 9489.1 9701.0 10645.6 10662.4 10678.6 11:21:15 INFO - PROCESS | 1641 | -------- Summary: end -------- 11:21:15 INFO - PROCESS | 1641 | 11:21:16 INFO - TEST-INFO | 1641: exit 0 11:21:16 INFO - TEST-OK | dromaeo_css | took 1117467ms 11:21:16 INFO - TEST-START | kraken 11:21:16 INFO - operating with platform_type : mac_ 11:21:16 INFO - Initialising browser for kraken test... 11:21:16 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpaYRjLB/profile http://localhost:49230/getInfo.html) 11:21:19 INFO - PROCESS | 1651 | 2017-03-01 11:21:19.382 plugin-container[1652:9981] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 11:21:19 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:21:19 INFO - PROCESS | 1651 | 2017-03-01 11:21:19.385 plugin-container[1652:9981] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 11:21:19 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:21:19 INFO - PROCESS | 1651 | 2017-03-01 11:21:19.385 plugin-container[1652:9981] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:21:19 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 11:21:19 INFO - PROCESS | 1651 | colorDepth:24 11:21:19 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 11:21:19 INFO - PROCESS | 1651 | __metrics 11:21:19 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 11:21:20 INFO - TEST-INFO | 1651: exit 0 11:21:20 INFO - Browser initialized. 11:21:20 INFO - Running cycle 1/1 for kraken test... 11:21:20 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpaYRjLB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:21:22 INFO - PROCESS | 1653 | 2017-03-01 11:21:22.644 plugin-container[1654:10095] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b47, name = 'com.apple.tsm.portname' 11:21:22 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:21:22 INFO - PROCESS | 1653 | 2017-03-01 11:21:22.644 plugin-container[1654:10095] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4b, name = 'com.apple.CFPasteboardClient' 11:21:22 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:21:22 INFO - PROCESS | 1653 | 2017-03-01 11:21:22.644 plugin-container[1654:10095] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:21:32 INFO - PROCESS | 1653 | RSS: Main: 234254336 11:21:32 INFO - PROCESS | 1653 | 11:21:35 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-beat-detection) 11:21:36 INFO - PROCESS | 1653 | RSS: Main: 248303616 11:21:36 INFO - PROCESS | 1653 | 11:21:39 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-dft) 11:21:39 INFO - PROCESS | 1653 | RSS: Main: 255803392 11:21:39 INFO - PROCESS | 1653 | 11:21:43 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-fft) 11:21:43 INFO - PROCESS | 1653 | RSS: Main: 258523136 11:21:43 INFO - PROCESS | 1653 | 11:21:45 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-oscillator) 11:21:46 INFO - PROCESS | 1653 | RSS: Main: 260993024 11:21:46 INFO - PROCESS | 1653 | 11:21:48 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:21:49 INFO - PROCESS | 1653 | RSS: Main: 261230592 11:21:49 INFO - PROCESS | 1653 | 11:21:51 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-darkroom) 11:21:52 INFO - PROCESS | 1653 | RSS: Main: 266682368 11:21:52 INFO - PROCESS | 1653 | 11:21:55 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-desaturate) 11:21:56 INFO - PROCESS | 1653 | RSS: Main: 266129408 11:21:56 INFO - PROCESS | 1653 | 11:21:59 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49230/tests/kraken/driver.html?testName=json-parse-financial) 11:21:59 INFO - PROCESS | 1653 | RSS: Main: 266100736 11:21:59 INFO - PROCESS | 1653 | 11:22:01 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49230/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:22:02 INFO - PROCESS | 1653 | RSS: Main: 265834496 11:22:02 INFO - PROCESS | 1653 | 11:22:04 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:22:05 INFO - PROCESS | 1653 | RSS: Main: 265797632 11:22:05 INFO - PROCESS | 1653 | 11:22:07 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:22:08 INFO - PROCESS | 1653 | RSS: Main: 265895936 11:22:08 INFO - PROCESS | 1653 | 11:22:10 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:22:11 INFO - PROCESS | 1653 | RSS: Main: 266199040 11:22:11 INFO - PROCESS | 1653 | 11:22:14 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:22:15 INFO - PROCESS | 1653 | RSS: Main: 266293248 11:22:15 INFO - PROCESS | 1653 | 11:22:17 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49230/tests/kraken/driver.html?testName=ai-astar) 11:22:17 INFO - PROCESS | 1653 | RSS: Main: 268251136 11:22:17 INFO - PROCESS | 1653 | 11:22:17 INFO - PROCESS | 1653 | __start_tp_report 11:22:17 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 11:22:17 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 11:22:17 INFO - PROCESS | 1653 | |i|pagename|runs| 11:22:17 INFO - PROCESS | 1653 | |0;ai-astar;105;117;118;119;118;118;118;121;121;154 11:22:17 INFO - PROCESS | 1653 | |1;audio-beat-detection;192;145;145;157;151;114;112;117;136;115 11:22:17 INFO - PROCESS | 1653 | |2;audio-dft;226;261;211;214;205;198;198;228;210;274 11:22:17 INFO - PROCESS | 1653 | |3;audio-fft;81;71;63;87;62;63;62;63;76;60 11:22:17 INFO - PROCESS | 1653 | |4;audio-oscillator;113;94;117;99;77;73;84;71;68;82 11:22:17 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;91;92;93;94;97;91;91;91;92;91 11:22:17 INFO - PROCESS | 1653 | |6;imaging-darkroom;98;100;99;99;95;131;99;98;108;100 11:22:17 INFO - PROCESS | 1653 | |7;imaging-desaturate;93;105;93;93;90;92;122;148;113;91 11:22:17 INFO - PROCESS | 1653 | |8;json-parse-financial;65;64;63;65;64;67;68;76;100;95 11:22:17 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;69;86;109;114;115;65;95;102;101;86 11:22:17 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;89;91;90;95;94;93;111;78;107;110 11:22:17 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;148;142;144;147;144;143;146;147;193;145 11:22:17 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;177;176;178;184;179;178;175;178;173;214 11:22:17 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;68;74;86;90;91;66;67;83;61;83 11:22:17 INFO - PROCESS | 1653 | __end_tp_report 11:22:17 INFO - PROCESS | 1653 | __start_cc_report 11:22:17 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1006 11:22:17 INFO - PROCESS | 1653 | __end_cc_report 11:22:17 INFO - PROCESS | 1653 | __startTimestamp1488396137402__endTimestamp 11:22:17 INFO - PROCESS | 1653 | 11:22:17 INFO - PROCESS | 1653 | ------- Summary: start ------- 11:22:17 INFO - PROCESS | 1653 | Number of tests: 14 11:22:17 INFO - PROCESS | 1653 | 11:22:17 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:120.90 Median:118.50 stddev:12.48 (10.5%) stddev-sans-first:11.83 11:22:17 INFO - PROCESS | 1653 | Values: 105.0 117.0 118.0 119.0 118.0 118.0 118.0 121.0 121.0 154.0 11:22:17 INFO - PROCESS | 1653 | 11:22:17 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:138.40 Median:145.00 stddev:25.32 (17.5%) stddev-sans-first:17.95 11:22:17 INFO - PROCESS | 1653 | Values: 192.0 145.0 145.0 157.0 151.0 114.0 112.0 117.0 136.0 115.0 11:22:17 INFO - PROCESS | 1653 | 11:22:17 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:222.50 Median:220.00 stddev:25.92 (11.8%) stddev-sans-first:27.46 11:22:17 INFO - PROCESS | 1653 | Values: 226.0 261.0 211.0 214.0 205.0 198.0 198.0 228.0 210.0 274.0 11:22:17 INFO - PROCESS | 1653 | 11:22:17 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:68.80 Median:67.00 stddev:9.47 (14.1%) stddev-sans-first:8.96 11:22:17 INFO - PROCESS | 1653 | Values: 81.0 71.0 63.0 87.0 62.0 63.0 62.0 63.0 76.0 60.0 11:22:17 INFO - PROCESS | 1653 | 11:22:17 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:87.80 Median:79.50 stddev:17.35 (21.8%) stddev-sans-first:15.83 11:22:17 INFO - PROCESS | 1653 | Values: 113.0 94.0 117.0 99.0 77.0 73.0 84.0 71.0 68.0 82.0 11:22:17 INFO - PROCESS | 1653 | 11:22:17 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:92.30 Median:92.00 stddev:1.95 (2.1%) stddev-sans-first:2.01 11:22:17 INFO - PROCESS | 1653 | Values: 91.0 92.0 93.0 94.0 97.0 91.0 91.0 91.0 92.0 91.0 11:22:17 INFO - PROCESS | 1653 | 11:22:17 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:102.70 Median:98.00 stddev:10.48 (10.7%) stddev-sans-first:10.97 11:22:17 INFO - PROCESS | 1653 | Values: 98.0 100.0 99.0 99.0 95.0 131.0 99.0 98.0 108.0 100.0 11:22:17 INFO - PROCESS | 1653 | 11:22:17 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:104.00 Median:91.50 stddev:18.90 (20.7%) stddev-sans-first:19.62 11:22:17 INFO - PROCESS | 1653 | Values: 93.0 105.0 93.0 93.0 90.0 92.0 122.0 148.0 113.0 91.0 11:22:17 INFO - PROCESS | 1653 | 11:22:17 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:72.70 Median:66.00 stddev:13.63 (20.7%) stddev-sans-first:14.17 11:22:17 INFO - PROCESS | 1653 | Values: 65.0 64.0 63.0 65.0 64.0 67.0 68.0 76.0 100.0 95.0 11:22:17 INFO - PROCESS | 1653 | 11:22:17 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:94.20 Median:67.00 stddev:17.55 (26.2%) stddev-sans-first:16.08 11:22:17 INFO - PROCESS | 1653 | Values: 69.0 86.0 109.0 114.0 115.0 65.0 95.0 102.0 101.0 86.0 11:22:17 INFO - PROCESS | 1653 | 11:22:17 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:95.80 Median:90.50 stddev:10.49 (11.6%) stddev-sans-first:10.83 11:22:17 INFO - PROCESS | 1653 | Values: 89.0 91.0 90.0 95.0 94.0 93.0 111.0 78.0 107.0 110.0 11:22:17 INFO - PROCESS | 1653 | 11:22:17 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:149.90 Median:146.50 stddev:15.26 (10.4%) stddev-sans-first:16.17 11:22:17 INFO - PROCESS | 1653 | Values: 148.0 142.0 144.0 147.0 144.0 143.0 146.0 147.0 193.0 145.0 11:22:17 INFO - PROCESS | 1653 | 11:22:17 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.20 Median:178.00 stddev:11.88 (6.7%) stddev-sans-first:12.50 11:22:17 INFO - PROCESS | 1653 | Values: 177.0 176.0 178.0 184.0 179.0 178.0 175.0 178.0 173.0 214.0 11:22:17 INFO - PROCESS | 1653 | 11:22:17 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.90 Median:83.00 stddev:10.98 (13.2%) stddev-sans-first:11.16 11:22:17 INFO - PROCESS | 1653 | Values: 68.0 74.0 86.0 90.0 91.0 66.0 67.0 83.0 61.0 83.0 11:22:17 INFO - PROCESS | 1653 | -------- Summary: end -------- 11:22:17 INFO - PROCESS | 1653 | 11:22:18 INFO - TEST-INFO | 1653: exit 0 11:22:18 INFO - TEST-OK | kraken | took 61807ms 11:22:18 INFO - SUITE-END | took 1179s 11:22:18 INFO - Completed test suite (00:19:39) 11:22:18 INFO - javascript benchmark 11:22:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7112.136830769886, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3367.7474189435784, 3380.323935212957, 3395.9320813583727, 3403.2342722887347, 3425.7773244739105, 5601.971990140054, 5627.9718601406985, 5692.174634677975, 5702.971485142573, 5744.080947048473, 5599.216109744639, 5602.13166959121, 5644.209810626506, 5689.9146512802345, 5789.652620842742, 5089.643724939256, 5095.261187127864, 5097.5667068299235, 5179.663321884084, 5213.739313034352, 5162.174052151657, 5214.0875346814155, 5218.973905130469, 5247.921281180786, 5250.7112108834, 5042.621803364762, 5080.288759573663, 5117.769700363493, 5150.124478838607, 5234.895302093937, 5125.282460455539, 5152.896942061137, 5153.845384638467, 5160.432352441228, 5193.844184674465, 4984.77568509418, 5112.41207261166, 5150.896982060375, 5156.561692256163, 5173.922391164136, 4358.825646974117, 4980.7509624518625, 5239.449857764918, 5389.8113566025395, 5405.459454054602, 4832.492588278215, 5955.642661440327, 5956.076808094752, 5963.0459126539545, 5971.940280597182, 6032.517398608101, 6047.667378294192, 6051.940910340717, 6061.333253342129, 6080.665563394012, 5549.6115271930985, 5607.551395888319, 5647.209390685307, 5651.6043876928225, 5705.429457054344, 5392.840539284073, 6135.355158799479, 6168.784092556739, 6222.097795819581, 6237.75048998035, 4803.255495398149, 5827.475527202572, 6081.118237855575, 6084.209052823193, 6132.877342453126, 5172.7155006475, 6247.75008999644, 6250.4374606285655, 6255.655938923403, 6278.340774218641, 5975.940240597538, 6057.3336932937345, 6221.906671399933, 6222.3466536013975, 6232.25212974446, 4738.3129446230105, 5601.775928962796, 6104.06507294887, 6129.970128114947, 6143.354947730513, 4824.0834241494, 4929.112759703216, 4938.950610493921, 4959.702417854868, 4975.751212439363, 3670.4127339626066, 4423.093265880487, 4430.778461076933, 4431.534688857688, 4460.330950357472, 5602.159676048625, 5634.915476267859, 5709.029464990961, 5722.685252311079, 5855.326637436664, 6209.565330426917, 6240.687965601702, 6243.875122497616, 6246.312905580383, 6293.811185664437, 5886.676232807151, 5889.293284805851, 5926.436988486087, 5926.703664816828, 5968.373320801254, 5273.947260527422, 5313.256144139824, 5326.094563924142, 5455.9633669602645, 5487.368952570426, 6169.198004259597, 6195.938040619536, 6203.16257305276, 6229.470495007975, 6338.683065846782, 6185.443310102112, 6232.937670623417, 6233.532485063548, 6244.0, 6256.468200202851, 6266.530010249158, 6317.368263173829, 6321.905171422524, 6338.112664227104, 6338.461230795434, 5827.329857066492, 5831.650100993954, 5876.059830427112, 5978.641281523122, 6082.2093127893095, 6004.369541198112, 6209.96895015522, 6220.968895155676, 6229.937700622936, 6234.688265586793, 5697.116946873241, 5808.738606762621, 6023.45788879003, 6062.090686397076, 6104.389561044038, 5041.571466425395, 6117.553376797925, 6122.245686735438, 6137.285055221507, 6146.893559159215, 5689.573282003949, 5716.885662286814, 5956.017257152695, 5968.0, 5985.5810093294795, 6077.6353418795015, 6096.451319381277, 6119.837693858789, 6124.418180272867, 6126.30393285589, 5969.074793407029, 5993.340732519507, 5997.850053748692, 6026.035834266497, 6027.457528822427], "value": 5593.768534012343, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [467.3480494709882, 477.9259214821704, 488.99511503859554, 490.13980464285174, 511.2254933775331, 37958.05104872376, 40110.0, 40275.0, 40288.395674064916, 40307.39538906911, 41263.79368103163, 41719.0, 41824.0, 41849.790751046276, 41867.58132418675, 7411.2959268869645, 9108.59011344491, 9337.486438245895, 9345.532723363805, 9368.063193680646, 7562.243775622449, 11001.504932278067, 11240.100791936666, 11245.381504017316, 11422.942885285562, 11423.371714555697, 11525.423728813526, 11552.191346605741, 11589.594364197255, 11601.825972610417, 11529.654110376701, 11547.364894930775, 11598.76802463946, 11615.883841161565, 11621.360825154614, 11000.889991100066, 11219.326840389604, 11285.887141128565, 11297.661070167907, 11311.604093856675, 27280.181594552196, 38434.80782596069, 40305.79847100775, 40388.59611403907, 40397.0, 3256.625488068879, 3786.5198295116465, 3818.6372294632242, 3839.884803455901, 3960.5049368828895, 23518.0, 29752.0, 29921.850390748124, 30710.078697638884, 30961.845190774126, 2785.7214278572183, 2801.86524457595, 3030.8939187128344, 3054.77808876449, 3104.9689503104905, 424684.0, 425730.0, 427564.0, 431867.0, 431879.0, 48626.5137348622, 49905.50094499081, 51756.482435175916, 60254.0, 60464.395356046756, 62862.0, 63552.36447635465, 64262.03606945806, 67694.3230567688, 68073.65963170251, 13726.039177257695, 13799.310034498234, 13875.37560809766, 13886.4445422184, 13908.37412316428, 10368.066873981377, 10518.158547316196, 10593.947030264799, 10607.204459665556, 10642.56325396077, 13253.403596838165, 16400.933939293907, 16489.91755041217, 16564.254608542644, 16662.50012499627, 6564.7374105036215, 6609.339066093448, 6622.0, 6670.566413183127, 6682.632455214913, 739.3530660671912, 806.6289506826809, 816.6814942172557, 833.6082041440513, 858.9184027517376, 23898.88050559736, 25786.742132578434, 27296.908123675228, 28375.858120709265, 28761.0, 1018.607835983137, 1034.7723500829804, 1052.315994603514, 1053.6154303679102, 1162.8546431696038, 12578.81131783024, 13549.864501355058, 15074.698506030038, 15456.845431545766, 15604.68790624204, 481737.0, 630964.0, 633519.0, 767517.0, 767967.0, 52488.900443981816, 52808.0, 53009.939801203756, 53150.0, 53486.0, 1287.2791236907362, 1320.7820709583004, 1349.4399824073096, 1351.9526816561372, 1358.8437329707012, 27720.722792771816, 27768.0, 28033.79493076055, 28265.434691305647, 28470.715292846806, 43621.781891090344, 44493.55506444894, 44743.88140296388, 44854.3271850929, 44987.55012449834, 378717.0, 453222.0, 455140.0, 456293.0, 456513.0, 16632.0, 18873.05634718231, 21583.784162158176, 24166.275011749676, 24376.0, 41057.589424105376, 41296.587034129276, 41359.793201033805, 41452.79273603613, 42779.78610107054, 33515.0, 34243.31513369669, 34333.0, 34508.65491345055, 35226.64773352234, 34115.65884341125, 34208.82895585506, 34792.0, 35130.64869351274, 35198.824005879804, 33643.0, 34612.134696632784, 34615.65384346225, 34621.0, 34665.960021199404, 28386.85806570954, 30574.0, 30792.076237712903, 30932.690673093883, 31278.0, 20370.89814550977, 22340.77659223452, 22980.885095575082, 23166.957486912794, 23191.420214494774], "value": 18743.240550224942, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.72426128925866, 379.3351704277449, 394.4360610180634, 396.1206122408254, 413.6463323858101, 12855.421506032228, 14284.500042498514, 19619.5095122622, 20858.687119693215, 20930.16279348826, 20577.0, 21614.675779863315, 21799.564008719815, 22227.110915563357, 22492.662610060863, 4573.0, 5408.0, 5653.20855080287, 5717.514011309044, 5887.322957859858, 4826.734529600871, 5879.911801322984, 5887.646741195542, 5890.764369425217, 5901.321348044966, 3631.4734363517273, 6357.777477788279, 6499.38255865692, 6577.539572229945, 6600.80197594073, 5610.990021796075, 6143.354947730513, 6533.44465720412, 6575.0, 6617.239017512999, 4879.316895634614, 5663.235463212455, 5674.9716251418895, 5702.600817942745, 5716.57125715573, 22667.54664906709, 22835.657465138036, 25966.480670386485, 26359.472810543873, 26533.805978730812, 2387.6657267982323, 2563.3976015636313, 2636.657234559514, 2765.170448865332, 2782.5687018512162, 9905.851412228822, 10273.897261027369, 10310.536025878853, 12249.203801752858, 14313.42746290147, 1738.6435780664933, 1785.2680401035514, 1789.159095225242, 1799.8200179982027, 1822.334847780564, 87768.12231877727, 107550.0, 113648.4317578415, 114634.0, 124735.0, 11440.0, 11825.940870295766, 11845.111616628787, 12019.879801201876, 12180.208286461351, 15208.098987626547, 15706.0, 15721.371145154295, 15726.92136539333, 15788.526344209693, 7619.860936567497, 7890.250426209501, 7898.0, 8012.679492820338, 8017.639206235734, 7167.569945803269, 7169.533980291264, 7408.518446301081, 7423.44324175689, 7433.776986690408, 4088.7733679895914, 4173.332266837291, 4767.165745994437, 4776.808927642855, 4828.155072862235, 1968.635802376565, 2056.270024141438, 2084.0, 2085.48905518149, 2092.393205970282, 979.8040391921644, 989.9158571521357, 1003.4781913404988, 1008.1632245236436, 1042.3902017319954, 21297.574048519255, 21554.784452155593, 21613.135474581155, 21687.240946566795, 21696.457588560414, 1006.8187726209353, 1016.6289304403839, 1025.8512515685184, 1049.506731836036, 1055.519738518972, 9162.725118246462, 11016.669499915015, 11268.605598804003, 11335.829962550568, 11350.375729334966, 543698.0, 616461.0, 705478.0, 713152.0, 713176.0, 97672.04655906842, 113197.0, 140565.3773849055, 143339.2833035828, 146052.0, 1008.909198172168, 1043.1446214104362, 1081.4268437728017, 1081.9729506762394, 1087.1574529739357, 16671.16590179648, 19402.0298985053, 19462.8053719461, 19512.902435487733, 19819.335037344037, 54025.81896873305, 55743.44256557382, 56184.7295196288, 56198.8760224785, 57058.7147064262, 53474.73262633662, 78803.60598196973, 82732.0, 83155.16844831474, 84742.88142796479, 8102.0, 8222.794430139294, 8231.547264900488, 8494.872576911228, 8508.234258916727, 11585.0, 12053.0, 12343.506259749509, 12476.18904771187, 12778.361081945679, 10097.293189476666, 10740.194485413469, 10848.45757712127, 10878.782424351626, 10934.726631834268, 8959.41763785352, 9187.540622968958, 9544.379615324982, 10134.645287414905, 10414.010668986433, 10881.891181088087, 11058.0, 11119.888801111885, 11236.381998990135, 11512.769744605228, 7278.636068196463, 8027.678892844221, 8151.184881511801, 8203.671853126047, 8571.95714021426, 10689.893101069201, 10748.193885458466, 10886.727831803952, 10901.618443354444, 10941.945290273497], "value": 9626.336692648714, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1436.9856301436982, 1443.2350854047356, 1443.5236371997235, 1451.4411951398708, 1484.2949598940504, 7417.740379086733, 8260.256576908077, 8443.691227859676, 8682.348823838214, 8713.390062695615, 8475.57622118893, 8529.402941794076, 8637.568121593926, 8647.91352086479, 8656.91343086569, 2273.3520946530284, 2310.318456055468, 2314.5023819878725, 2316.664083707861, 2323.6282194848827, 2278.840481166319, 2299.666548350488, 2310.90756369745, 2311.087120587367, 2323.2913961241816, 2247.763984781591, 2291.255342013844, 2293.117149897285, 2310.87290199039, 2311.3528212100637, 2043.5402034542183, 2182.879941603211, 2261.604219261623, 2275.3174047785683, 2302.7466978632337, 2249.460129568908, 2297.2533926473798, 2315.0161181497797, 2317.154238702861, 2322.7677232276637, 8516.70191543293, 8633.784155396103, 8692.826143477096, 8706.695265665734, 8716.084811094806, 5139.820106296299, 5209.531142197183, 5258.13240815265, 5292.470752924715, 5339.225812257239, 3257.201985513541, 3355.312161006988, 3485.790852548855, 3490.4415293553166, 3521.5950165731015, 1183.5088438298096, 1208.746163305707, 1216.2945491614842, 1228.8279640850199, 1242.43469221504, 8751.912480875173, 9713.90286097137, 10055.095041446304, 10778.191635627281, 10803.4058126803, 4331.4152589400655, 4373.453318335208, 4534.659900507475, 4550.590446859798, 4555.2711566149255, 8816.206541411304, 8830.293576513863, 8851.424657397378, 8857.070007649232, 8858.689945851864, 5052.141136006888, 5107.361579802525, 5139.948600514022, 5141.974290128525, 5153.484651534892, 4575.519570445122, 4754.643401744814, 4795.520447955177, 4798.0, 4810.542998415145, 3631.2555926035097, 3643.795503720264, 3660.396034654252, 3703.129764505339, 3718.7356298858517, 1139.5783560082657, 1144.7939370913316, 1151.7120719820045, 1161.96514104577, 1163.8196079607674, 735.7424901284614, 834.3700506117842, 896.8161526886972, 909.5225006871445, 929.0802105915095, 4924.43369012561, 5439.700816455053, 5468.125099984063, 5692.743826527816, 5810.88378232433, 862.4523427623504, 882.955852207387, 986.3736527305213, 993.4536005197112, 1014.1379827146866, 3667.853285868589, 3772.5661548921676, 3814.675752561008, 3864.4976153099915, 3903.2583809076186, 7290.0, 9463.769709937664, 9652.710418687451, 10001.949990250003, 10035.197184225244, 9818.361806482555, 9968.202543796478, 10036.899631003742, 10058.849117263248, 10060.69817905464, 6854.931450685529, 7513.2111128331835, 7898.170692077364, 7924.326432253208, 8025.317847982987, 4969.781056140807, 5094.719790411563, 5456.181572764117, 5873.089671100986, 5947.732352044255, 7361.411087113125, 7623.809404764926, 7959.522428654299, 8757.817694611145, 9462.905370946477, 9133.223675987616, 9404.341696081208, 9549.474778887228, 9557.569909354235, 9566.330356874952, 7197.316254955771, 7265.237150099375, 7284.344409003003, 7309.8903516446235, 7324.157721861841, 7879.763607091796, 7943.602819859099, 7964.402669799904, 7971.601419929097, 7972.083210430643, 7164.283571642794, 7208.243134471015, 7266.818329541804, 7320.743773968099, 7400.074990626172, 7077.079979602619, 7092.787216383516, 7111.822204444723, 7125.1093613298335, 7148.964255178691, 6088.634681919099, 6311.842203944754, 6411.8717625646295, 6424.38968298011, 6429.357064293533, 4912.140375434356, 4934.235193545006, 5006.224035274538, 5056.418511871182, 5079.568236699922, 3505.5793304803587, 4286.785660717014, 4454.777261136995, 4600.42494688164, 4730.503297153887], "value": 4358.262597088524, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.4095598915827, 359.0986623574826, 362.65910044558115, 363.04338069187696, 371.7676452217364, 4964.135147555397, 5052.6210534209895, 5240.6898275431095, 5420.566354691625, 5503.91744123838, 4437.889052773682, 4490.4835943866465, 5383.3849845046425, 5428.4300148484435, 5520.641158324716, 3931.783751893645, 4044.9191016179657, 4088.080181959065, 4096.201240758053, 4107.650849677766, 3422.5379573757473, 4087.20299541588, 4091.8288326546867, 4101.138760860208, 4104.733192342503, 4071.5724848890886, 4083.3466645336603, 4086.4891888513935, 4100.323446631317, 4118.546959834416, 2923.795334326598, 3687.8893633191046, 4003.3794761811964, 4096.774677392742, 4134.524529679095, 3186.267158553546, 3466.4107101792756, 3487.4541584643353, 3494.5107684924133, 3502.824858757052, 4378.387025816388, 4378.555765897617, 4585.931211031837, 5274.05067087924, 5297.417284098746, 3702.981485092584, 4404.339349097629, 4421.093675796486, 4676.532346765331, 4715.292706094079, 5234.33049144935, 5507.862303442406, 5699.629524080921, 5713.286363863481, 5748.626339287975, 3508.3684936711384, 3749.887503374903, 3815.8361699681586, 3893.844246230176, 3904.1606054698377, 4514.977425112854, 5060.418051924003, 5669.9433005669825, 5872.471477567039, 6083.574149809515, 3110.98444507776, 3312.536244925664, 3388.3053973935284, 3762.247550489913, 3795.582485926546, 3999.6000399960353, 4000.8999775005277, 4010.438538604598, 4019.4975628046495, 4027.234825383168, 4469.0, 4660.067986402733, 4666.813327466931, 4668.5564871337165, 4686.531346865286, 4741.620670346433, 4790.401199850019, 5516.862078448071, 5769.88460230793, 5792.710364481759, 1144.4035570379274, 1152.467218599261, 1153.961434708752, 1261.4449642157422, 1272.9045321600918, 826.2852632472988, 848.0, 858.0304256190465, 879.2438502887512, 883.4301875290402, 4487.932681009787, 4545.886257866844, 4569.908601827945, 4955.603551715854, 5143.116946705213, 5097.745112744348, 5207.401148867929, 5458.536024437888, 5605.962896864094, 5618.381977982419, 4476.977615111904, 4698.859034228979, 4896.586280628464, 4964.975175124101, 5122.0, 2873.5545990371525, 2961.851907404621, 2978.717021882918, 2996.4456575533595, 3002.309468822183, 5319.962607291541, 5561.0, 5829.941700583025, 5881.3530511643685, 5952.523798096142, 5165.974170129125, 5496.835094947158, 5625.5092400513895, 5753.583494932055, 5834.416558344216, 4872.537108974641, 5023.723695196799, 5087.542121209109, 5554.916791225673, 5667.008273552112, 4849.442314133919, 5911.3201981772645, 5938.851528711817, 5967.791127310524, 6018.307894592178, 5013.423456302572, 5502.697351645698, 5730.942690573208, 5792.652440853562, 5834.883302334015, 4972.477889821516, 5890.49515538254, 6131.478824299985, 6193.504519638508, 6239.4713295242955, 3886.378179491268, 4216.79821250942, 4251.234777740036, 4285.799976006723, 4300.182965236595, 5229.320188375486, 5554.416786237387, 5796.391378905154, 5837.591368604327, 5910.468057874812, 5771.7402716877, 5774.351646924266, 5781.8554536136935, 5810.73851676684, 5915.1551491530845, 4617.799372163195, 4695.5304469552775, 5535.446455354432, 5743.8564035899435, 5791.218185545066, 4452.710573812692, 5032.3961124665275, 5590.385057643574, 5618.690971996416, 5649.774009039593, 4672.976635116803, 5778.913316300175, 5803.187553742395, 5852.483432124057, 5872.941270587239, 4830.7826147822725, 5004.899902002013, 5011.724355160355, 5668.234788303691, 5698.601097923106], "value": 4071.4915881630523, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [672.5762769455242, 691.9238883722793, 731.9597422141782, 751.5791156952107, 858.5278097046624, 14020.439182432703, 14438.639034024154, 14461.493847715332, 14477.565673029812, 14503.98472106953, 14368.281585920715, 14431.783523247159, 14552.636184095403, 14569.27153642319, 14577.05249158804, 552.7512619321301, 887.8490656588364, 898.285862739124, 905.814308066848, 906.5875026862761, 657.4871600151865, 761.889526018727, 897.9865302020474, 923.487464457226, 925.1673493855517, 929.0848514213494, 952.4047470331043, 953.5232383808096, 954.8901876284212, 957.0716405087054, 809.2069771623783, 930.5579849571467, 943.9386439881386, 953.4660590069549, 953.8378475659155, 606.9514438844882, 681.6966449929783, 701.1586096684, 703.5919166883195, 703.7642389799423, 15074.170920599363, 15311.234438278152, 15352.846471535366, 15377.461788837449, 15542.067475951479, 14126.858731412656, 14248.446897507652, 14313.78529322061, 14344.56966291013, 14651.560453186421, 10797.83803242952, 10962.342259464458, 10965.50655220517, 11034.393108379036, 11353.489092990836, 3629.800360980145, 3660.5790334111653, 3794.1273507093247, 3798.1264309209037, 3806.619338066199, 77383.22616773816, 90874.0, 94299.52850235705, 94553.52723236408, 95716.0, 9575.377600455948, 9940.45327506994, 10717.678469645924, 10766.730831729114, 10940.288881222854, 21434.464138396354, 21470.355889323346, 21802.345929622137, 21958.45103872384, 21978.780212197995, 7180.60506672138, 7400.814979625552, 7405.5926924019695, 7451.125046108503, 7627.084749830056, 4074.0425999890003, 4118.979405102956, 4125.608067233608, 4134.689898257643, 4140.896477588084, 3468.3930312195266, 3469.8438570264398, 3525.347810655037, 3538.15084379747, 3570.571531416195, 660.8744338575656, 661.5633681770008, 666.0941120076692, 679.5582871133704, 682.1745687717818, 602.8312072619674, 609.58852774378, 623.1867413025954, 635.4344633276388, 653.1867824558456, 7471.066116735408, 8518.105598912149, 8862.601182946783, 9512.524373781422, 9561.330706850593, 654.4802510335267, 656.6920998251486, 660.3099760750027, 660.3924389561615, 673.3905815237218, 4005.2990726622725, 4415.7517113284985, 4453.415194179828, 4606.101810146989, 4698.765061746899, 92378.53810730904, 95558.0, 96066.0393396057, 97429.0, 97850.5107474458, 84837.0, 85374.71937920936, 85854.0, 85964.28071438661, 87611.56194218989, 60675.786484270066, 72272.55454890872, 75632.62183689047, 77683.83474248004, 78484.21515784883, 8831.867521987304, 8847.071057538866, 8867.778305542413, 8898.555072246232, 9038.548072596475, 35071.0, 40355.78043207534, 44285.77857110694, 45738.31392529048, 46783.0, 56353.0, 56654.0, 64291.67854160699, 66007.66996164988, 66294.66852665706, 10062.34594751368, 12242.144681695523, 12598.874011260137, 12611.621651350475, 12748.980081593265, 13873.79189312141, 13926.58220253394, 14179.64550886236, 14653.0, 14759.262036898326, 12942.482300707867, 12984.285864277555, 13010.869891301345, 13177.670558236121, 13747.243901585109, 12958.935205323913, 13035.0, 13264.86735132675, 13307.06850520493, 13564.457421703022, 12782.296973666538, 12869.099163058496, 12916.0, 12997.15518491295, 13547.322633868464, 10648.89351106479, 10816.837747433952, 10825.56697732082, 10852.403117828595, 10866.293690910066, 9489.098535639103, 9700.951495242478, 10645.574177032831, 10662.360258384522, 10678.57285708563], "value": 7225.659921112967, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1608.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 117.0, 118.0, 119.0, 118.0, 118.0, 118.0, 121.0, 121.0, 154.0], "value": 120.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 145.0, 145.0, 157.0, 151.0, 114.0, 112.0, 117.0, 136.0, 115.0], "value": 138.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [226.0, 261.0, 211.0, 214.0, 205.0, 198.0, 198.0, 228.0, 210.0, 274.0], "value": 222.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 71.0, 63.0, 87.0, 62.0, 63.0, 62.0, 63.0, 76.0, 60.0], "value": 68.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 94.0, 117.0, 99.0, 77.0, 73.0, 84.0, 71.0, 68.0, 82.0], "value": 87.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 93.0, 94.0, 97.0, 91.0, 91.0, 91.0, 92.0, 91.0], "value": 92.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 100.0, 99.0, 99.0, 95.0, 131.0, 99.0, 98.0, 108.0, 100.0], "value": 102.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 105.0, 93.0, 93.0, 90.0, 92.0, 122.0, 148.0, 113.0, 91.0], "value": 104.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 63.0, 65.0, 64.0, 67.0, 68.0, 76.0, 100.0, 95.0], "value": 72.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 86.0, 109.0, 114.0, 115.0, 65.0, 95.0, 102.0, 101.0, 86.0], "value": 94.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 91.0, 90.0, 95.0, 94.0, 93.0, 111.0, 78.0, 107.0, 110.0], "value": 95.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 142.0, 144.0, 147.0, 144.0, 143.0, 146.0, 147.0, 193.0, 145.0], "value": 149.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 176.0, 178.0, 184.0, 179.0, 178.0, 175.0, 178.0, 173.0, 214.0], "value": 181.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 74.0, 86.0, 90.0, 91.0, 66.0, 67.0, 83.0, 61.0, 83.0], "value": 76.9, "unit": "score"}]}]} 11:22:18 INFO - Return code: 0 11:22:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:22:18 ERROR - # TBPL SUCCESS # 11:22:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:22:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:22:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:22:18 INFO - 2017-03-01 11:22:18.742 screenresolution[1658:10481] starting screenresolution argv=screenresolution get 11:22:18 INFO - 2017-03-01 11:22:18.744 screenresolution[1658:10481] Display 0: 1600x1200x32@60 11:22:18 INFO - 2017-03-01 11:22:18.750 screenresolution[1659:10484] starting screenresolution argv=screenresolution list 11:22:18 INFO - Available Modes on Display 0 11:22:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:22:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:22:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:22:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:22:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:22:18 INFO - Intel Iris: 11:22:18 INFO - Chipset Model: Intel Iris 11:22:18 INFO - Type: GPU 11:22:18 INFO - Bus: Built-In 11:22:18 INFO - VRAM (Dynamic, Max): 1536 MB 11:22:18 INFO - Vendor: Intel (0x8086) 11:22:18 INFO - Device ID: 0x0a2e 11:22:18 INFO - Revision ID: 0x0009 11:22:18 INFO - Displays: 11:22:18 INFO - Display: 11:22:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:22:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:22:18 INFO - Display Serial Number: 1600x1200 60 11:22:18 INFO - Main Display: Yes 11:22:18 INFO - Mirror: Off 11:22:18 INFO - Online: Yes 11:22:18 INFO - Rotation: Supported 11:22:18 INFO - Return code: 0 11:22:18 INFO - Running post-action listener: _resource_record_post_action 11:22:18 INFO - [mozharness: 2017-03-01 19:22:18.986725Z] Finished run-tests step (success) 11:22:18 INFO - Running post-run listener: _resource_record_post_run 11:22:19 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 37415424; Write bytes: 554113024; Read time: 378; Write time: 1740 11:22:19 INFO - TinderboxPrint: CPU usage
54.5% 11:22:19 INFO - TinderboxPrint: I/O read bytes / time
37,415,424 / 378 11:22:19 INFO - TinderboxPrint: I/O write bytes / time
554,113,024 / 1,740 11:22:19 INFO - TinderboxPrint: CPU idle
2,189.6 (45.5%) 11:22:19 INFO - TinderboxPrint: CPU system
971.6 (20.2%) 11:22:19 INFO - TinderboxPrint: CPU user
1,648.4 (34.3%) 11:22:19 INFO - TinderboxPrint: Swap in / out
289,869,824 / 0 11:22:19 INFO - install - Wall time: 23s; CPU: 55.0%; Read bytes: 238582272; Write bytes: 230230016; Read time: 17544; Write time: 457 11:22:19 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 36444672; Write bytes: 299081728; Read time: 359; Write time: 1235 11:22:19 INFO - Running post-run listener: _upload_blobber_files 11:22:19 INFO - Blob upload gear active. 11:22:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:22:19 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:22:19 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'] 11:22:19 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 11:22:19 INFO - (blobuploader) - INFO - Open directory for files ... 11:22:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:22:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:22:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:22:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:22:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:22:21 INFO - (blobuploader) - INFO - Done attempting. 11:22:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:22:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:22:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:22:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:22:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:22:22 INFO - (blobuploader) - INFO - Done attempting. 11:22:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:22:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:22:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:22:23 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:22:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:22:23 INFO - (blobuploader) - INFO - Done attempting. 11:22:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:22:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:22:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:22:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:22:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:22:25 INFO - (blobuploader) - INFO - Done attempting. 11:22:25 INFO - (blobuploader) - INFO - Iteration through files over. 11:22:25 INFO - Return code: 0 11:22:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:22:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:22:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc32a9e5d4a0389f59c04ede430373cfbc43121d4198fc33645a9940973c527af6dab45fe6041b148632a33dc4c933b4c767902e454f12b343a8768eb07836eb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6881e7ea4a4d9e93388dab31805fb0e3166d2dddeb741b92daa90162924d58713831adcb36fd52a89f660ac713a16e8ba0e0bab6946d85437b9d0358e2bda71a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce3c83a8458e2db8f3b191950b55b6157f58758acb67ced65079a09cbca669d16cb1a6ccb75cb0697387df3eaeea4c788a02d357e46aa76e54df9f60e8d04d20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9abdeda797514e84cb2ff5701ed36b0b1421c42e9237952a5885272dfaeb8bdfaf8054a7e32432c95ac82557815f1865b0c80341c09413e53fddc09487ae5389"} 11:22:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:22:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:22:25 INFO - Contents: 11:22:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc32a9e5d4a0389f59c04ede430373cfbc43121d4198fc33645a9940973c527af6dab45fe6041b148632a33dc4c933b4c767902e454f12b343a8768eb07836eb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6881e7ea4a4d9e93388dab31805fb0e3166d2dddeb741b92daa90162924d58713831adcb36fd52a89f660ac713a16e8ba0e0bab6946d85437b9d0358e2bda71a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce3c83a8458e2db8f3b191950b55b6157f58758acb67ced65079a09cbca669d16cb1a6ccb75cb0697387df3eaeea4c788a02d357e46aa76e54df9f60e8d04d20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9abdeda797514e84cb2ff5701ed36b0b1421c42e9237952a5885272dfaeb8bdfaf8054a7e32432c95ac82557815f1865b0c80341c09413e53fddc09487ae5389"} 11:22:25 INFO - Running post-run listener: copy_logs_to_upload_dir 11:22:25 INFO - Copying logs to upload dir... 11:22:25 INFO - mkdir: /builds/slave/test/build/upload/logs 11:22:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.175880 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-01 11:22:25.892701) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-01 11:22:25.895959) ========= 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.kMd4P7D7jn/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.5s1tQuJeH2/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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/bc32a9e5d4a0389f59c04ede430373cfbc43121d4198fc33645a9940973c527af6dab45fe6041b148632a33dc4c933b4c767902e454f12b343a8768eb07836eb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6881e7ea4a4d9e93388dab31805fb0e3166d2dddeb741b92daa90162924d58713831adcb36fd52a89f660ac713a16e8ba0e0bab6946d85437b9d0358e2bda71a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce3c83a8458e2db8f3b191950b55b6157f58758acb67ced65079a09cbca669d16cb1a6ccb75cb0697387df3eaeea4c788a02d357e46aa76e54df9f60e8d04d20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9abdeda797514e84cb2ff5701ed36b0b1421c42e9237952a5885272dfaeb8bdfaf8054a7e32432c95ac82557815f1865b0c80341c09413e53fddc09487ae5389"} 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.010623 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/bc32a9e5d4a0389f59c04ede430373cfbc43121d4198fc33645a9940973c527af6dab45fe6041b148632a33dc4c933b4c767902e454f12b343a8768eb07836eb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6881e7ea4a4d9e93388dab31805fb0e3166d2dddeb741b92daa90162924d58713831adcb36fd52a89f660ac713a16e8ba0e0bab6946d85437b9d0358e2bda71a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce3c83a8458e2db8f3b191950b55b6157f58758acb67ced65079a09cbca669d16cb1a6ccb75cb0697387df3eaeea4c788a02d357e46aa76e54df9f60e8d04d20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9abdeda797514e84cb2ff5701ed36b0b1421c42e9237952a5885272dfaeb8bdfaf8054a7e32432c95ac82557815f1865b0c80341c09413e53fddc09487ae5389"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-01 11:22:25.930292) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 11:22:25.930640) ========= 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.kMd4P7D7jn/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.5s1tQuJeH2/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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.005219 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 11:22:25.979122) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-01 11:22:25.979391) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-01 11:22:25.979677) ========= ========= Total master_lag: 0.18 =========