builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0194 starttime: 1489008112.06 results: success (0) buildid: 20170308131954 builduid: 03bf22f5cf9d46c68a280f8c8107fe1b revision: fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-08 13:21:52.059668) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-08 13:21:52.060137) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-08 13:21:52.060426) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-08 13:21:52.089472) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 13:21:52.089898) ========= 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.Pf9qpTb84F/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.YQcYEGwgFr/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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-08 13:21:52-- 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% 9.47M=0.001s 2017-03-08 13:21:52 (9.47 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.078208 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 13:21:52.192666) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 13:21:52.193439) ========= 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.Pf9qpTb84F/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.YQcYEGwgFr/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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.027796 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 13:21:52.243042) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 13:21:52.243404) ========= 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.Pf9qpTb84F/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.YQcYEGwgFr/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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-08 13:21:52,348 truncating revision to first 12 chars 2017-03-08 13:21:52,348 Setting DEBUG logging. 2017-03-08 13:21:52,348 attempt 1/10 2017-03-08 13:21:52,348 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-08 13:21:52,982 unpacking tar archive at: mozilla-central-fc9f6f7e8e86/testing/mozharness/ program finished with exit code 0 elapsedTime=0.924456 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 13:21:53.192168) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-08 13:21:53.192491) ========= script_repo_revision: fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-08 13:21:53.192891) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-08 13:21:53.193199) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-08 13:21:53.212914) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-08 13:21:53.213274) ========= /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.Pf9qpTb84F/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.YQcYEGwgFr/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 13:21:53 INFO - MultiFileLogger online at 20170308 13:21:53 in /builds/slave/test 13:21:53 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 13:21:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:21:53 INFO - {'append_to_log': False, 13:21:53 INFO - 'base_work_dir': '/builds/slave/test', 13:21:53 INFO - 'blob_upload_branch': 'Firefox', 13:21:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:21:53 INFO - 'branch': 'Firefox', 13:21:53 INFO - 'buildbot_json_path': 'buildprops.json', 13:21:53 INFO - 'config_files': ('talos/mac_config.py',), 13:21:53 INFO - 'default_actions': ('clobber', 13:21:53 INFO - 'read-buildbot-config', 13:21:53 INFO - 'download-and-extract', 13:21:53 INFO - 'populate-webroot', 13:21:53 INFO - 'create-virtualenv', 13:21:53 INFO - 'install', 13:21:53 INFO - 'run-tests'), 13:21:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:21:53 INFO - 'download_minidump_stackwalk': True, 13:21:53 INFO - 'download_symbols': 'ondemand', 13:21:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:21:53 INFO - 'tooltool.py': '/tools/tooltool.py', 13:21:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:21:53 INFO - '/tools/misc-python/virtualenv.py')}, 13:21:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:21:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:21:53 INFO - 'installer_path': 'installer.exe', 13:21:53 INFO - 'log_level': 'info', 13:21:53 INFO - 'log_name': 'talos', 13:21:53 INFO - 'log_to_console': True, 13:21:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:21:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:21:53 INFO - 'opt_config_files': (), 13:21:53 INFO - 'pip_index': False, 13:21:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:21:53 INFO - 'cmd': ('bash', 13:21:53 INFO - '-c', 13:21:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:21:53 INFO - 'enabled': True, 13:21:53 INFO - 'halt_on_failure': False, 13:21:53 INFO - 'name': 'check_screen_resolution'},), 13:21:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:21:53 INFO - 'cmd': ('bash', 13:21:53 INFO - '-c', 13:21:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:21:53 INFO - 'enabled': True, 13:21:53 INFO - 'halt_on_failure': False, 13:21:53 INFO - 'name': 'check_screen_resolution'},), 13:21:53 INFO - 'run_cmd_checks_enabled': True, 13:21:53 INFO - 'sps_profile': False, 13:21:53 INFO - 'sps_profile_interval': 0, 13:21:53 INFO - 'suite': 'dromaeojs-e10s', 13:21:53 INFO - 'system_bits': '32', 13:21:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:21:53 INFO - 'title': 't-yosemite-r7-0194', 13:21:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:21:53 INFO - 'use_talos_json': True, 13:21:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:21:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:21:53 INFO - 'work_dir': 'build'} 13:21:53 INFO - [mozharness: 2017-03-08 21:21:53.431459Z] Running clobber step. 13:21:53 INFO - Running pre-action listener: _resource_record_pre_action 13:21:53 INFO - Running main action method: clobber 13:21:53 INFO - rmtree: /builds/slave/test/build 13:21:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:21:55 INFO - Running post-action listener: _resource_record_post_action 13:21:55 INFO - [mozharness: 2017-03-08 21:21:55.989439Z] Finished clobber step (success) 13:21:55 INFO - [mozharness: 2017-03-08 21:21:55.989633Z] Running read-buildbot-config step. 13:21:55 INFO - Running pre-action listener: _resource_record_pre_action 13:21:55 INFO - Running main action method: read_buildbot_config 13:21:55 INFO - Using buildbot properties: 13:21:55 INFO - { 13:21:55 INFO - "project": "", 13:21:55 INFO - "product": "firefox", 13:21:55 INFO - "script_repo_revision": "production", 13:21:55 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 13:21:55 INFO - "repository": "", 13:21:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 13:21:55 INFO - "buildid": "20170308131954", 13:21:55 INFO - "stage_platform": "macosx64", 13:21:55 INFO - "basedir": "/builds/slave/test", 13:21:55 INFO - "buildnumber": 88, 13:21:55 INFO - "platform": "yosemite_r7", 13:21:55 INFO - "mozci_request": { 13:21:55 INFO - "priority": -1, 13:21:55 INFO - "type": "trigger_all_talos_jobs", 13:21:55 INFO - "times": 1 13:21:55 INFO - }, 13:21:55 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 13:21:55 INFO - "slavebuilddir": "test", 13:21:55 INFO - "branch": "mozilla-central", 13:21:55 INFO - "revision": "fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd", 13:21:55 INFO - "slavename": "t-yosemite-r7-0194", 13:21:55 INFO - "builduid": "03bf22f5cf9d46c68a280f8c8107fe1b", 13:21:55 INFO - "repo_path": "mozilla-central" 13:21:55 INFO - } 13:21:55 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.dmg. 13:21:55 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. 13:21:55 INFO - Running post-action listener: _resource_record_post_action 13:21:55 INFO - [mozharness: 2017-03-08 21:21:55.993362Z] Finished read-buildbot-config step (success) 13:21:55 INFO - [mozharness: 2017-03-08 21:21:55.993451Z] Running download-and-extract step. 13:21:55 INFO - Running pre-action listener: _resource_record_pre_action 13:21:55 INFO - Running main action method: download_and_extract 13:21:55 INFO - mkdir: /builds/slave/test/build/tests 13:21:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:21:55 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.test_packages.json 13:21:55 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 13:21:55 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 13:21:56 INFO - Downloaded 1199 bytes. 13:21:56 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 13:21:56 INFO - Using the following test package requirements: 13:21:56 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 13:21:56 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 13:21:56 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 13:21:56 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 13:21:56 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 13:21:56 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 13:21:56 INFO - u'jsshell-mac.zip'], 13:21:56 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 13:21:56 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 13:21:56 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 13:21:56 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 13:21:56 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 13:21:56 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 13:21:56 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 13:21:56 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 13:21:56 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 13:21:56 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 13:21:56 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 13:21:56 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:21:56 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 13:21:56 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 13:21:56 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 13:21:57 INFO - Expected file size: 51504348 13:21:57 INFO - Obtained file size: 51504348 13:22:12 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 13:22:12 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 13:22:12 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 13:22:12 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 13:22:13 INFO - Expected file size: 51504348 13:22:13 INFO - Obtained file size: 51504348 13:22:29 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 13:22:29 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 13:22:29 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 13:22:29 INFO - Expected file size: 14403647 13:22:29 INFO - Obtained file size: 14403647 13:22:30 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')]} 13:22:30 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.dmg 13:22:30 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 13:22:30 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 13:22:31 INFO - Downloaded 70417704 bytes. 13:22:31 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.dmg 13:22:31 INFO - mkdir: /builds/slave/test/properties 13:22:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:22:31 INFO - Writing to file /builds/slave/test/properties/build_url 13:22:31 INFO - Contents: 13:22:31 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.dmg 13:22:32 INFO - Running post-action listener: _resource_record_post_action 13:22:32 INFO - Running post-action listener: set_extra_try_arguments 13:22:32 INFO - [mozharness: 2017-03-08 21:22:32.193122Z] Finished download-and-extract step (success) 13:22:32 INFO - [mozharness: 2017-03-08 21:22:32.193273Z] Running populate-webroot step. 13:22:32 INFO - Running pre-action listener: _resource_record_pre_action 13:22:32 INFO - Running main action method: populate_webroot 13:22:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:22:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:22:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:22:32 INFO - '--tppagecycles', 13:22:32 INFO - '7'], 13:22:32 INFO - 'tests': ['tsvgm']}}, 13:22:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:22:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:22:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:22:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:22:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:22:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:22:32 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 13:22:32 INFO - 'talos_options': ['--disable-e10s'], 13:22:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:22:32 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:22:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:22:32 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 13:22:32 INFO - 'talos_options': ['--disable-e10s'], 13:22:32 INFO - 'tests': ['damp', 'tps']}, 13:22:32 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:22:32 INFO - 'tests': ['damp', 'tps']}, 13:22:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:22:32 INFO - 'tests': ['dromaeo_dom']}, 13:22:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:22:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:22:32 INFO - 'tests': ['basic_compositor_video']}, 13:22:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:22:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:22:32 INFO - 'tests': ['a11yr', 13:22:32 INFO - 'ts_paint', 13:22:32 INFO - 'tpaint', 13:22:32 INFO - 'sessionrestore', 13:22:32 INFO - 'sessionrestore_no_auto_restore', 13:22:32 INFO - 'tabpaint']}, 13:22:32 INFO - 'other-e10s': {'tests': ['a11yr', 13:22:32 INFO - 'ts_paint', 13:22:32 INFO - 'tpaint', 13:22:32 INFO - 'sessionrestore', 13:22:32 INFO - 'sessionrestore_no_auto_restore', 13:22:32 INFO - 'tabpaint']}, 13:22:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:22:32 INFO - 'ts_paint', 13:22:32 INFO - 'tpaint', 13:22:32 INFO - 'sessionrestore', 13:22:32 INFO - 'sessionrestore_no_auto_restore', 13:22:32 INFO - 'tabpaint']}, 13:22:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:22:32 INFO - 'ts_paint', 13:22:32 INFO - 'tpaint', 13:22:32 INFO - 'sessionrestore', 13:22:32 INFO - 'sessionrestore_no_auto_restore', 13:22:32 INFO - 'tabpaint']}, 13:22:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:22:32 INFO - 'tests': ['a11yr', 13:22:32 INFO - 'ts_paint', 13:22:32 INFO - 'tpaint', 13:22:32 INFO - 'sessionrestore', 13:22:32 INFO - 'sessionrestore_no_auto_restore', 13:22:32 INFO - 'tabpaint']}, 13:22:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:22:32 INFO - 'tests': ['a11yr', 13:22:32 INFO - 'ts_paint', 13:22:32 INFO - 'tpaint', 13:22:32 INFO - 'sessionrestore', 13:22:32 INFO - 'sessionrestore_no_auto_restore', 13:22:32 INFO - 'tabpaint']}, 13:22:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:22:32 INFO - 'tests': ['tsvgx', 13:22:32 INFO - 'tsvgr_opacity', 13:22:32 INFO - 'tart', 13:22:32 INFO - 'tscrollx', 13:22:32 INFO - 'cart', 13:22:32 INFO - 'tsvg_static']}, 13:22:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:22:32 INFO - 'tsvgr_opacity', 13:22:32 INFO - 'tart', 13:22:32 INFO - 'tscrollx', 13:22:32 INFO - 'cart', 13:22:32 INFO - 'tsvg_static']}, 13:22:32 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 13:22:32 INFO - 'talos_options': ['--disable-e10s'], 13:22:32 INFO - 'tests': ['tp5o']}, 13:22:32 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:22:32 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 13:22:32 INFO - 'talos_options': ['--disable-e10s', 13:22:32 INFO - '--xperf_path', 13:22:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:22:32 INFO - 'tests': ['tp5n']}, 13:22:32 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:22:32 INFO - 'talos_options': ['--xperf_path', 13:22:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:22:32 INFO - 'tests': ['tp5n']}}, 13:22:32 INFO - 'talos.zip': {'path': '', 13:22:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:22:32 INFO - Running post-action listener: _resource_record_post_action 13:22:32 INFO - [mozharness: 2017-03-08 21:22:32.211481Z] Finished populate-webroot step (success) 13:22:32 INFO - [mozharness: 2017-03-08 21:22:32.211594Z] Running create-virtualenv step. 13:22:32 INFO - Running pre-action listener: _resource_record_pre_action 13:22:32 INFO - Running main action method: create_virtualenv 13:22:32 INFO - Creating virtualenv /builds/slave/test/build/venv 13:22:32 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 13:22:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:22:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:22:32 INFO - Using real prefix '/tools/python27' 13:22:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:22:33 INFO - Installing distribute.............................................................................................................................................................................................done. 13:22:35 INFO - Installing pip.................done. 13:22:35 INFO - Return code: 0 13:22:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:22:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:22:36 INFO - Reading from file tmpfile_stdout 13:22:36 INFO - Output received: 13:22:36 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 13:22:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:22:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:22:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:22:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:22:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:22:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108a40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff47adafb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10881ed30>, '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 0x108745db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108813be8>, '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.Pf9qpTb84F/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.YQcYEGwgFr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 13:22:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:22:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:22:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Pf9qpTb84F/Render', 13:22:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:22:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:22:36 INFO - 'HOME': '/Users/cltbld', 13:22:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:22:36 INFO - 'LOGNAME': 'cltbld', 13:22:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:22:36 INFO - 'MOZ_NO_REMOTE': '1', 13:22:36 INFO - 'NO_EM_RESTART': '1', 13:22:36 INFO - 'PAGER': '/bin/cat', 13:22:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:22:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:22:36 INFO - 'PWD': '/builds/slave/test', 13:22:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:22:36 INFO - 'SHELL': '/bin/bash', 13:22:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YQcYEGwgFr/Listeners', 13:22:36 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 13:22:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:22:36 INFO - 'USER': 'cltbld', 13:22:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:22:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:22:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:22:36 INFO - 'XPC_FLAGS': '0x0', 13:22:36 INFO - 'XPC_SERVICE_NAME': '0', 13:22:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:22:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:22:36 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 13:22:36 INFO - Cleaning up... 13:22:36 INFO - Return code: 0 13:22:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:22:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:22:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:22:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:22:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:22:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108a40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff47adafb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10881ed30>, '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 0x108745db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108813be8>, '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.Pf9qpTb84F/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.YQcYEGwgFr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 13:22:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 13:22:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 13:22:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Pf9qpTb84F/Render', 13:22:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:22:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:22:36 INFO - 'HOME': '/Users/cltbld', 13:22:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:22:36 INFO - 'LOGNAME': 'cltbld', 13:22:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:22:36 INFO - 'MOZ_NO_REMOTE': '1', 13:22:36 INFO - 'NO_EM_RESTART': '1', 13:22:36 INFO - 'PAGER': '/bin/cat', 13:22:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:22:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:22:36 INFO - 'PWD': '/builds/slave/test', 13:22:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:22:36 INFO - 'SHELL': '/bin/bash', 13:22:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YQcYEGwgFr/Listeners', 13:22:36 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 13:22:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:22:36 INFO - 'USER': 'cltbld', 13:22:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:22:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:22:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:22:36 INFO - 'XPC_FLAGS': '0x0', 13:22:36 INFO - 'XPC_SERVICE_NAME': '0', 13:22:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:22:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:22:36 INFO - Downloading/unpacking psutil>=3.1.1 13:22:36 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 13:22:36 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 13:22:36 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 13:22:36 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 13:22:36 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 13:22:36 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 13:22:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:22:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:22:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:22:40 INFO - Installing collected packages: psutil 13:22:40 INFO - Running setup.py install for psutil 13:22:40 INFO - building 'psutil._psutil_osx' extension 13:22:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 13:22:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 13:22:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 13:22:41 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 13:22:41 INFO - building 'psutil._psutil_posix' extension 13:22:41 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 13:22:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:22:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:22:41 INFO - ^ 13:22:41 INFO - 1 warning generated. 13:22:41 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 13:22:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:22:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:22:41 INFO - Successfully installed psutil 13:22:41 INFO - Cleaning up... 13:22:41 INFO - Return code: 0 13:22:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:22:41 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')]} 13:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:22:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:41 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')]} 13:22:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:22:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x108a40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff47adafb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10881ed30>, '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 0x108745db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108813be8>, '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.Pf9qpTb84F/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.YQcYEGwgFr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 13:22:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 13:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.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 13:22:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Pf9qpTb84F/Render', 13:22:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:22:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:22:41 INFO - 'HOME': '/Users/cltbld', 13:22:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:22:41 INFO - 'LOGNAME': 'cltbld', 13:22:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:22:41 INFO - 'MOZ_NO_REMOTE': '1', 13:22:41 INFO - 'NO_EM_RESTART': '1', 13:22:41 INFO - 'PAGER': '/bin/cat', 13:22:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:22:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:22:41 INFO - 'PWD': '/builds/slave/test', 13:22:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:22:41 INFO - 'SHELL': '/bin/bash', 13:22:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YQcYEGwgFr/Listeners', 13:22:41 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 13:22:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:22:41 INFO - 'USER': 'cltbld', 13:22:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:22:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:22:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:22:41 INFO - 'XPC_FLAGS': '0x0', 13:22:41 INFO - 'XPC_SERVICE_NAME': '0', 13:22:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:22:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:22:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:22:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:22:41 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 13:22:41 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 13:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:22:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:22:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:22:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:22:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:22:44 INFO - Installing collected packages: mozsystemmonitor 13:22:44 INFO - Running setup.py install for mozsystemmonitor 13:22:44 INFO - Successfully installed mozsystemmonitor 13:22:44 INFO - Cleaning up... 13:22:44 INFO - Return code: 0 13:22:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:22:44 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')]} 13:22:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:22:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:44 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')]} 13:22:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:22:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:44 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 0x108a40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff47adafb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10881ed30>, '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 0x108745db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108813be8>, '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.Pf9qpTb84F/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.YQcYEGwgFr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 13:22:44 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 13:22:44 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 13:22:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Pf9qpTb84F/Render', 13:22:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:22:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:22:44 INFO - 'HOME': '/Users/cltbld', 13:22:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:22:44 INFO - 'LOGNAME': 'cltbld', 13:22:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:22:44 INFO - 'MOZ_NO_REMOTE': '1', 13:22:44 INFO - 'NO_EM_RESTART': '1', 13:22:44 INFO - 'PAGER': '/bin/cat', 13:22:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:22:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:22:44 INFO - 'PWD': '/builds/slave/test', 13:22:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:22:44 INFO - 'SHELL': '/bin/bash', 13:22:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YQcYEGwgFr/Listeners', 13:22:44 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 13:22:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:22:44 INFO - 'USER': 'cltbld', 13:22:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:22:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:22:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:22:44 INFO - 'XPC_FLAGS': '0x0', 13:22:44 INFO - 'XPC_SERVICE_NAME': '0', 13:22:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:22:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:22:45 INFO - Downloading/unpacking jsonschema==2.5.1 13:22:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:22:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:22:45 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 13:22:45 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 13:22:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:22:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:22:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:22:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:22:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:22:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:22:47 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 13:22:47 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 13:22:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:22:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:22:48 INFO - Downloading functools32-3.2.3-2.tar.gz 13:22:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:22:48 INFO - warning: no files found matching '*.txt' 13:22:48 INFO - no previously-included directories found matching 'build' 13:22:48 INFO - no previously-included directories found matching 'dist' 13:22:48 INFO - no previously-included directories found matching '.git*' 13:22:48 INFO - Installing collected packages: jsonschema, functools32 13:22:48 INFO - Running setup.py install for functools32 13:22:48 INFO - warning: no files found matching '*.txt' 13:22:48 INFO - no previously-included directories found matching 'build' 13:22:48 INFO - no previously-included directories found matching 'dist' 13:22:48 INFO - no previously-included directories found matching '.git*' 13:22:48 INFO - Successfully installed jsonschema functools32 13:22:48 INFO - Cleaning up... 13:22:48 INFO - Return code: 0 13:22:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:22:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:22:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:22:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:22:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108a40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff47adafb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10881ed30>, '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 0x108745db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108813be8>, '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.Pf9qpTb84F/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.YQcYEGwgFr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 13:22:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 13:22:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 13:22:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Pf9qpTb84F/Render', 13:22:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:22:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:22:49 INFO - 'HOME': '/Users/cltbld', 13:22:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:22:49 INFO - 'LOGNAME': 'cltbld', 13:22:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:22:49 INFO - 'MOZ_NO_REMOTE': '1', 13:22:49 INFO - 'NO_EM_RESTART': '1', 13:22:49 INFO - 'PAGER': '/bin/cat', 13:22:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:22:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:22:49 INFO - 'PWD': '/builds/slave/test', 13:22:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:22:49 INFO - 'SHELL': '/bin/bash', 13:22:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YQcYEGwgFr/Listeners', 13:22:49 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 13:22:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:22:49 INFO - 'USER': 'cltbld', 13:22:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:22:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:22:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:22:49 INFO - 'XPC_FLAGS': '0x0', 13:22:49 INFO - 'XPC_SERVICE_NAME': '0', 13:22:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:22:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:22:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:22:49 INFO - Cleaning up... 13:22:49 INFO - Return code: 0 13:22:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:22:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:22:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:22:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:22:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:22:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108a40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff47adafb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10881ed30>, '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 0x108745db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108813be8>, '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.Pf9qpTb84F/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.YQcYEGwgFr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 13:22:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:22:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:22:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Pf9qpTb84F/Render', 13:22:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:22:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:22:49 INFO - 'HOME': '/Users/cltbld', 13:22:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:22:49 INFO - 'LOGNAME': 'cltbld', 13:22:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:22:49 INFO - 'MOZ_NO_REMOTE': '1', 13:22:49 INFO - 'NO_EM_RESTART': '1', 13:22:49 INFO - 'PAGER': '/bin/cat', 13:22:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:22:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:22:49 INFO - 'PWD': '/builds/slave/test', 13:22:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:22:49 INFO - 'SHELL': '/bin/bash', 13:22:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YQcYEGwgFr/Listeners', 13:22:49 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 13:22:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:22:49 INFO - 'USER': 'cltbld', 13:22:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:22:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:22:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:22:49 INFO - 'XPC_FLAGS': '0x0', 13:22:49 INFO - 'XPC_SERVICE_NAME': '0', 13:22:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:22:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:22:50 INFO - Downloading/unpacking blobuploader==1.2.4 13:22:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:22:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:22:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:22:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:22:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:22:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:22:52 INFO - Downloading blobuploader-1.2.4.tar.gz 13:22:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:22:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:22:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:22:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:22:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:22:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:22:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:22:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:22:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:22:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:22:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:22:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:22:54 INFO - Downloading docopt-0.6.1.tar.gz 13:22:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:22:54 INFO - Installing collected packages: blobuploader, requests, docopt 13:22:54 INFO - Running setup.py install for blobuploader 13:22:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:22:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:22:54 INFO - Running setup.py install for requests 13:22:55 INFO - Running setup.py install for docopt 13:22:55 INFO - Successfully installed blobuploader requests docopt 13:22:55 INFO - Cleaning up... 13:22:55 INFO - Return code: 0 13:22:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:22:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:22:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:22:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:22:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:22:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:55 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 0x108a40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff47adafb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10881ed30>, '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 0x108745db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108813be8>, '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.Pf9qpTb84F/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.YQcYEGwgFr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 13:22:55 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 13:22:55 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 13:22:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Pf9qpTb84F/Render', 13:22:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:22:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:22:55 INFO - 'HOME': '/Users/cltbld', 13:22:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:22:55 INFO - 'LOGNAME': 'cltbld', 13:22:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:22:55 INFO - 'MOZ_NO_REMOTE': '1', 13:22:55 INFO - 'NO_EM_RESTART': '1', 13:22:55 INFO - 'PAGER': '/bin/cat', 13:22:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:22:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:22:55 INFO - 'PWD': '/builds/slave/test', 13:22:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:22:55 INFO - 'SHELL': '/bin/bash', 13:22:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YQcYEGwgFr/Listeners', 13:22:55 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 13:22:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:22:55 INFO - 'USER': 'cltbld', 13:22:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:22:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:22:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:22:55 INFO - 'XPC_FLAGS': '0x0', 13:22:55 INFO - 'XPC_SERVICE_NAME': '0', 13:22:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:22:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:22:55 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-WrpgIB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:22:55 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-RU9Idn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:22:55 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-zo8sDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:22:55 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-M1JvbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:22:55 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-TPAFvQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:22:56 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-xaguXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:22:56 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-VdWz4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:22:56 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-KV5x_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:22:56 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-J0y47l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:22:56 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-QHfnmo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:22:56 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-3bThI_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:22:56 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-WXKb8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:22:56 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-_hcrIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:22:56 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-ifruZr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:22:57 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-N3STvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:22:57 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-kjRhGE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:22:57 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-D_I2Ea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:22:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:22:57 INFO - Running setup.py install for manifestparser 13:22:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:22:57 INFO - Running setup.py install for mozcrash 13:22:57 INFO - Running setup.py install for mozdebug 13:22:57 INFO - Running setup.py install for mozdevice 13:22:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:22:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:22:57 INFO - Running setup.py install for mozfile 13:22:58 INFO - Running setup.py install for mozhttpd 13:22:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:22:58 INFO - Running setup.py install for mozinfo 13:22:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:22:58 INFO - Running setup.py install for mozInstall 13:22:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:22:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:22:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:22:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:22:58 INFO - Running setup.py install for mozleak 13:22:58 INFO - Running setup.py install for mozlog 13:22:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:22:58 INFO - Running setup.py install for moznetwork 13:22:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:22:58 INFO - Running setup.py install for mozprocess 13:22:59 INFO - Running setup.py install for mozprofile 13:22:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:22:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:22:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:22:59 INFO - Running setup.py install for mozrunner 13:22:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:22:59 INFO - Running setup.py install for mozscreenshot 13:22:59 INFO - Running setup.py install for moztest 13:22:59 INFO - Running setup.py install for mozversion 13:22:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:22:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:22:59 INFO - Cleaning up... 13:22:59 INFO - Return code: 0 13:22:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:22: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')]} 13:22:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:22:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22: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')]} 13:22:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:22:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/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 0x108a40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff47adafb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10881ed30>, '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 0x108745db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108813be8>, '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.Pf9qpTb84F/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.YQcYEGwgFr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 13:22:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:22:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:22:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Pf9qpTb84F/Render', 13:22:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:22:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:22:59 INFO - 'HOME': '/Users/cltbld', 13:22:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:22:59 INFO - 'LOGNAME': 'cltbld', 13:22:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:22:59 INFO - 'MOZ_NO_REMOTE': '1', 13:22:59 INFO - 'NO_EM_RESTART': '1', 13:22:59 INFO - 'PAGER': '/bin/cat', 13:22:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:22:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:22:59 INFO - 'PWD': '/builds/slave/test', 13:22:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:22:59 INFO - 'SHELL': '/bin/bash', 13:22:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YQcYEGwgFr/Listeners', 13:22:59 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 13:22:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:22:59 INFO - 'USER': 'cltbld', 13:22:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:22:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:22:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:22:59 INFO - 'XPC_FLAGS': '0x0', 13:22:59 INFO - 'XPC_SERVICE_NAME': '0', 13:22:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:23:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:23:00 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-YRXYZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:23:00 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)) 13:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:23:00 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-jOTL5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:23:00 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)) 13:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:23:00 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-KQ8IXS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:23:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:23:00 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-V920aQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:23:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:23:00 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-mMsY9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:23:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 13:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:23:00 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-hPvxb5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:23:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 13:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:23:00 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-0DlU1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:23:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 13:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:23:00 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-zw6KA8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:23:01 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)) 13:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:23:01 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-l4JUdb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:23:01 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)) 13:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:23:01 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-QtEVSZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:23:01 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)) 13:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:23:01 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-ZTrKwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:23:01 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)) 13:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:23:01 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-LKCkCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:23:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 13:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:23:01 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-sUBq8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:23:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 13:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:23:01 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-zzyX8Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:23:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 13:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:23:01 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-0s15Kh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:23:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 13:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:23:01 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-R7D7pV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:23:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 13:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:23:01 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-rVos12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:23:02 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)) 13:23:02 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)) 13:23:02 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)) 13:23:02 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)) 13:23:02 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)) 13:23:02 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)) 13:23:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:23: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 13:23: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 13:23: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 13:23: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 13:23: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 13:23: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 13:23:04 INFO - Downloading blessings-1.6.tar.gz 13:23:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:23:04 INFO - Installing collected packages: blessings 13:23:04 INFO - Running setup.py install for blessings 13:23:04 INFO - Successfully installed blessings 13:23:04 INFO - Cleaning up... 13:23:05 INFO - Return code: 0 13:23:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:23:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:23:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:23:05 INFO - Reading from file tmpfile_stdout 13:23:05 INFO - Current package versions: 13:23:05 INFO - blessings == 1.6 13:23:05 INFO - blobuploader == 1.2.4 13:23:05 INFO - docopt == 0.6.1 13:23:05 INFO - functools32 == 3.2.3-2 13:23:05 INFO - jsonschema == 2.5.1 13:23:05 INFO - manifestparser == 1.1 13:23:05 INFO - mozInstall == 1.12 13:23:05 INFO - mozcrash == 1.0 13:23:05 INFO - mozdebug == 0.1 13:23:05 INFO - mozdevice == 0.49 13:23:05 INFO - mozfile == 1.2 13:23:05 INFO - mozhttpd == 0.7 13:23:05 INFO - mozinfo == 0.9 13:23:05 INFO - mozleak == 0.1 13:23:05 INFO - mozlog == 3.4 13:23:05 INFO - moznetwork == 0.27 13:23:05 INFO - mozprocess == 0.24 13:23:05 INFO - mozprofile == 0.28 13:23:05 INFO - mozrunner == 6.13 13:23:05 INFO - mozscreenshot == 0.1 13:23:05 INFO - mozsystemmonitor == 0.3 13:23:05 INFO - moztest == 0.8 13:23:05 INFO - mozversion == 1.4 13:23:05 INFO - psutil == 3.1.1 13:23:05 INFO - requests == 1.2.3 13:23:05 INFO - wsgiref == 0.1.2 13:23:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:23: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')]} 13:23:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:23:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23: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')]} 13:23:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:23:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23:05 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 0x108a40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff47adafb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10881ed30>, '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 0x108745db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108813be8>, '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.Pf9qpTb84F/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.YQcYEGwgFr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 13:23:05 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 13:23:05 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 13:23:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Pf9qpTb84F/Render', 13:23:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:23:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:23:05 INFO - 'HOME': '/Users/cltbld', 13:23:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:23:05 INFO - 'LOGNAME': 'cltbld', 13:23:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:23:05 INFO - 'MOZ_NO_REMOTE': '1', 13:23:05 INFO - 'NO_EM_RESTART': '1', 13:23:05 INFO - 'PAGER': '/bin/cat', 13:23:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:23:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:23:05 INFO - 'PWD': '/builds/slave/test', 13:23:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:23:05 INFO - 'SHELL': '/bin/bash', 13:23:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YQcYEGwgFr/Listeners', 13:23:05 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 13:23:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:23:05 INFO - 'USER': 'cltbld', 13:23:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:23:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:23:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:23:05 INFO - 'XPC_FLAGS': '0x0', 13:23:05 INFO - 'XPC_SERVICE_NAME': '0', 13:23:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:23:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 13:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 13:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 13:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 13:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 13:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 13:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 13:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:23:05 INFO - Cleaning up... 13:23:05 INFO - Return code: 0 13:23:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:23: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')]} 13:23:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:23:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23: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')]} 13:23:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:23:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108a40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff47adafb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10881ed30>, '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 0x108745db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108813be8>, '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.Pf9qpTb84F/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.YQcYEGwgFr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 13:23:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:23:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:23:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Pf9qpTb84F/Render', 13:23:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:23:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:23:05 INFO - 'HOME': '/Users/cltbld', 13:23:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:23:05 INFO - 'LOGNAME': 'cltbld', 13:23:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:23:05 INFO - 'MOZ_NO_REMOTE': '1', 13:23:05 INFO - 'NO_EM_RESTART': '1', 13:23:05 INFO - 'PAGER': '/bin/cat', 13:23:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:23:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:23:05 INFO - 'PWD': '/builds/slave/test', 13:23:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:23:05 INFO - 'SHELL': '/bin/bash', 13:23:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YQcYEGwgFr/Listeners', 13:23:05 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 13:23:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:23:05 INFO - 'USER': 'cltbld', 13:23:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:23:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:23:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:23:05 INFO - 'XPC_FLAGS': '0x0', 13:23:05 INFO - 'XPC_SERVICE_NAME': '0', 13:23:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:23:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:23:05 INFO - Cleaning up... 13:23:05 INFO - Return code: 0 13:23:05 INFO - Running post-action listener: _resource_record_post_action 13:23:05 INFO - Running post-action listener: _start_resource_monitoring 13:23:05 INFO - Starting resource monitoring. 13:23:05 INFO - [mozharness: 2017-03-08 21:23:05.865383Z] Finished create-virtualenv step (success) 13:23:05 INFO - [mozharness: 2017-03-08 21:23:05.866348Z] Running install step. 13:23:05 INFO - Running pre-action listener: _resource_record_pre_action 13:23:05 INFO - Running main action method: install 13:23:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:23:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:23:06 INFO - Reading from file tmpfile_stdout 13:23:06 INFO - Detecting whether we're running mozinstall >=1.0... 13:23:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:23:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:23:06 INFO - Reading from file tmpfile_stdout 13:23:06 INFO - Output received: 13:23:06 INFO - Usage: mozinstall [options] installer 13:23:06 INFO - Options: 13:23:06 INFO - -h, --help show this help message and exit 13:23:06 INFO - -d DEST, --destination=DEST 13:23:06 INFO - Directory to install application into. [default: 13:23:06 INFO - "/builds/slave/test"] 13:23:06 INFO - --app=APP Application being installed. [default: firefox] 13:23:06 INFO - mkdir: /builds/slave/test/build/application 13:23:06 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'] 13:23:06 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 13:23:28 INFO - Reading from file tmpfile_stdout 13:23:28 INFO - Output received: 13:23:28 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:23:28 INFO - Running post-action listener: _resource_record_post_action 13:23:28 INFO - [mozharness: 2017-03-08 21:23:28.255588Z] Finished install step (success) 13:23:28 INFO - [mozharness: 2017-03-08 21:23:28.255750Z] Running run-tests step. 13:23:28 INFO - Running pre-action listener: _resource_record_pre_action 13:23:28 INFO - Running main action method: run_tests 13:23:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:23:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:23:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:23:28 INFO - 2017-03-08 13:23:28.270 screenresolution[1622:7582] starting screenresolution argv=screenresolution get 13:23:28 INFO - 2017-03-08 13:23:28.273 screenresolution[1622:7582] Display 0: 1600x1200x32@60 13:23:28 INFO - 2017-03-08 13:23:28.279 screenresolution[1623:7584] starting screenresolution argv=screenresolution list 13:23:28 INFO - Available Modes on Display 0 13:23:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:23:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:23:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:23:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:23:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:23:28 INFO - Intel Iris: 13:23:28 INFO - Chipset Model: Intel Iris 13:23:28 INFO - Type: GPU 13:23:28 INFO - Bus: Built-In 13:23:28 INFO - VRAM (Dynamic, Max): 1536 MB 13:23:28 INFO - Vendor: Intel (0x8086) 13:23:28 INFO - Device ID: 0x0a2e 13:23:28 INFO - Revision ID: 0x0009 13:23:28 INFO - Displays: 13:23:28 INFO - Display: 13:23:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:23:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:23:28 INFO - Display Serial Number: 1600x1200 60 13:23:28 INFO - Main Display: Yes 13:23:28 INFO - Mirror: Off 13:23:28 INFO - Online: Yes 13:23:28 INFO - Rotation: Supported 13:23:28 INFO - Return code: 0 13:23:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:23:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:23:28 INFO - Python 2.7.3 13:23:28 INFO - Return code: 0 13:23:28 INFO - grabbing minidump binary from tooltool 13:23:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:23:28 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 0x10881ed30>, '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 0x108745db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108813be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:23:28 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 13:23:28 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 13:23:28 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 13:23:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:23:28 INFO - Return code: 0 13:23:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:23:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:23:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:23:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:23:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:23:28 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-0194', '--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 13:23:28 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-0194 --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 13:23:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Pf9qpTb84F/Render', 13:23:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:23:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:23:28 INFO - 'HOME': '/Users/cltbld', 13:23:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:23:28 INFO - 'LOGNAME': 'cltbld', 13:23:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:23:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:23:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:23:28 INFO - 'MOZ_NO_REMOTE': '1', 13:23:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:23:28 INFO - 'NO_EM_RESTART': '1', 13:23:28 INFO - 'PAGER': '/bin/cat', 13:23:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:23:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:23:28 INFO - 'PWD': '/builds/slave/test', 13:23:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:23:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:23:28 INFO - 'SHELL': '/bin/bash', 13:23:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YQcYEGwgFr/Listeners', 13:23:28 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 13:23:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:23:28 INFO - 'USER': 'cltbld', 13:23:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:23:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:23:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:23:28 INFO - 'XPC_FLAGS': '0x0', 13:23:28 INFO - 'XPC_SERVICE_NAME': '0', 13:23:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:23:28 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-0194', '--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 13:23:28 INFO - mozversion application_buildid: 20170214071445 13:23:28 INFO - mozversion application_changeset: fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd 13:23:28 INFO - mozversion application_display_name: Nightly 13:23:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:23:28 INFO - mozversion application_name: Firefox 13:23:28 INFO - mozversion application_remotingname: firefox 13:23:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 13:23:28 INFO - mozversion application_vendor: Mozilla 13:23:28 INFO - mozversion application_version: 54.0a1 13:23:28 INFO - mozversion platform_buildid: 20170214071445 13:23:28 INFO - mozversion platform_changeset: fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd 13:23:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 13:23:28 INFO - mozversion platform_version: 54.0a1 13:23:28 INFO - using testdate: 1489008208 13:23:28 INFO - actual date: 1489008208 13:23:28 INFO - starting webserver on 'localhost:49239' 13:23:28 INFO - SUITE-START | Running 2 tests 13:23:28 INFO - TEST-START | dromaeo_css 13:23:28 INFO - operating with platform_type : mac_ 13:23:28 INFO - Initialising browser for dromaeo_css test... 13:23:28 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmp7rYKhY/profile http://localhost:49239/getInfo.html) 13:23:31 INFO - PROCESS | 1633 | 2017-03-08 13:23:31.831 plugin-container[1635:7753] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3f, name = 'com.apple.tsm.portname' 13:23:31 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:23:31 INFO - PROCESS | 1633 | 2017-03-08 13:23:31.835 plugin-container[1635:7753] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 13:23:31 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:23:31 INFO - PROCESS | 1633 | 2017-03-08 13:23:31.835 plugin-container[1635:7753] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:23:31 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 13:23:31 INFO - PROCESS | 1633 | colorDepth:24 13:23:31 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 13:23:31 INFO - PROCESS | 1633 | __metrics 13:23:32 ERROR - PROCESS | 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 13:23:32 INFO - TEST-INFO | 1633: exit 0 13:23:32 INFO - Browser initialized. 13:23:32 INFO - Running cycle 1/1 for dromaeo_css test... 13:23:32 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmp7rYKhY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:23:35 INFO - PROCESS | 1636 | 2017-03-08 13:23:35.272 plugin-container[1637:7873] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 13:23:35 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:23:35 INFO - PROCESS | 1636 | 2017-03-08 13:23:35.272 plugin-container[1637:7873] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 13:23:35 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:23:35 INFO - PROCESS | 1636 | 2017-03-08 13:23:35.273 plugin-container[1637:7873] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:23:45 INFO - PROCESS | 1636 | RSS: Main: 232972288 13:23:45 INFO - PROCESS | 1636 | 13:26:48 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49239/tests/dromaeo/cssquery-ext.html) 13:26:50 INFO - PROCESS | 1636 | RSS: Main: 254877696 13:26:50 INFO - PROCESS | 1636 | 13:28:33 INFO - PROCESS | 1636 | ************************* 13:28:33 INFO - PROCESS | 1636 | A coding exception was thrown and uncaught in a Task. 13:28:33 INFO - PROCESS | 1636 | 13:28:33 ERROR - PROCESS | 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 13:28:33 INFO - PROCESS | 1636 | Full stack: 13:28:33 INFO - PROCESS | 1636 | ************************* 13:29:52 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-ext.html (next: http://localhost:49239/tests/dromaeo/cssquery-jquery.html) 13:29:53 INFO - PROCESS | 1636 | RSS: Main: 260423680 13:29:53 INFO - PROCESS | 1636 | 13:32:55 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49239/tests/dromaeo/cssquery-mootools.html) 13:32:55 INFO - PROCESS | 1636 | RSS: Main: 272359424 13:32:55 INFO - PROCESS | 1636 | 13:35:57 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49239/tests/dromaeo/cssquery-prototype.html) 13:35:58 INFO - PROCESS | 1636 | RSS: Main: 264327168 13:35:58 INFO - PROCESS | 1636 | 13:39:01 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49239/tests/dromaeo/cssquery-yui.html) 13:39:02 INFO - PROCESS | 1636 | RSS: Main: 267747328 13:39:02 INFO - PROCESS | 1636 | 13:42:04 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-yui.html (next: http://localhost:49239/tests/dromaeo/cssquery-dojo.html) 13:42:05 INFO - PROCESS | 1636 | RSS: Main: 269393920 13:42:05 INFO - PROCESS | 1636 | 13:42:05 INFO - PROCESS | 1636 | __start_tp_report 13:42:05 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 13:42:05 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 13:42:05 INFO - PROCESS | 1636 | |i|pagename|runs| 13:42:05 INFO - PROCESS | 1636 | |0;dojo.html;3284.458064419371;3361.428557145285;3367.444371678672;3388.7289016878653;3402.897913062609;4292.399064131024;4960.710215344014;5496.532794712454;5583.35791383991;5676.631018983763;4728.621710263179;5526.171074338851;5590.385057643656;5691.686957217351;5705.4579814917515;5062.594992400608;5071.518205770446;5145.228215767646;5203.98522288154;5243.108671525831;5130.204818253177;5143.408508021569;5208.921866172011;5260.132078207111;5269.394019687747;5010.574101201402;5228.843134705965;5232.189010703347;5268.631195816294;5301.151815709487;5019.974900125512;5084.262781896604;5117.950820081875;5125.769340379692;5143.8456846294675;5147.845564633067;5178.741062946838;5180.792768289264;5181.611379146579;5203.19350500673;5249.816256431007;5651.208830763697;5705.800296989627;5709.600327977043;5715.971420142914;6027.728752206117;6076.939230607726;6156.815295541141;6198.132261483396;6200.658963756991;5331.546818520431;6135.508280839769;6157.138000679908;6166.229221347332;6198.256209254873;5825.097109947922;5843.795467158714;5848.707564621752;5851.678157701324;5871.794487192928;6096.512279017668;6206.534509911775;6218.689065546704;6226.501879849601;6247.937520624736;5925.111233315038;5955.3151387590115;5963;6127.356627554132;6175.228096487939;6117.388261173847;6143.603994442171;6144.740328232701;6146.846328841725;6199.287081985539;6031.487323577545;6047.667378294148;6053.757849685963;6060.121282414026;6221.191245138193;6115.575014303891;6145.617236121837;6189.412005859436;6203.16257305258;6257.155284036688;4806.110869489155;4957.157283261854;4958.958618690043;4967.975160124176;4975.203967365204;4320.805563749639;4343.0662407581885;4367.410399596078;4447.066116115652;4466.129104824528;4989.725565093955;5679.346875109332;5949.494292985059;5954.779270249588;5997.160397544348;6177.402847745927;6227.377262273736;6233.688315584202;6242.719077641517;6251.624902505864;5601.943980560224;5926.140709597171;5940.495057920039;5946.316173640001;5993.760249590055;5258.789648414097;5278.4721527846905;5315.096433606296;5316.627836051439;5341.5459685927135;6109.4653969264045;6113.506711826627;6146.385361463817;6146.53900957439;6153.07703844409;5191.870203244949;6111.602745821508;6134.619710565087;6139.048447490646;6146.139540464249;5567.582431317586;6137.263528376623;6143.232095988002;6147.538934579835;6199.938000620116;5267.73661316925;6147.108669242845;6177.81466556004;6181.81454556364;6185.783497577563;5113.463086376026;5203.635745497779;6202;6205.47253483459;6210.689465526796;5571.637843540157;5766.106253530709;5834.445727655866;6055.212822333068;6101.725422356093;5901.29184497863;6005.819825405245;6081.726322315595;6103.298120715996;6118.131909153847;5298.602604804578;5312.787488500417;5687.317521897398;5949.851253718519;5982.521398288213;5874.236349274738;5998.4601385875485;6081.209442772411;6091.208142941567;6117.990531562243;5686.317641883001;5892.145638882424;5966.925953328442;5986.281646202484;6003.279606447254 13:42:05 INFO - PROCESS | 1636 | |1;ext.html;450.65299719216193;467.7544289248144;512.5873671694285;513.809890340574;538.7440965541366;33901.83049084754;38109.809450952744;39475.60524394755;39594.604053959454;39597;35225.82387088061;36185.55257789692;36291.2741745165;37534.62465375345;37829;9210.355275130743;9404.059594040575;9421.340506164572;9438.197753190987;9441.480718560477;9194.448333100036;9262.259019278476;9303.162715355616;9319.161275485205;9362.438253704799;9341.252699784001;9365.953170234174;9375.718728438158;9387.859182112274;9404.341696081243;9259.861102083474;9288.767780805534;9365.063493650649;9375.10936461035;9400.388974716621;9258.72223833286;9260.120288572574;9291.814163716688;9350;9361.15749582534;36008.81995590005;36039.81980090109;36278.637213628055;36307;36368.63631363679;3727.8881633551036;3733.2720119576693;3806.0865392305923;3807.2195199983935;3970.583088775666;21209;27327.726722732717;28413.57379639307;28564.71435285662;28605.14184574466;2994.910152695401;3125.484295091307;3177.459831828595;3239.2225865792275;3240.91897702557;429217;432971;435017;436230;446151.30773038755;40734.38898416526;52114.95770084577;52989.7350513245;53176.46823531792;56067.71966140225;56035.43964560302;56407.71796141075;56501.71749141228;56518.7174064127;56561.434385655615;14193.361298741582;14218.075825071337;14388.928055359656;14421.639459013399;14422.927885360716;11050.392228427514;11102.056325212287;11120.888791111985;11141.888581114246;11145.554177832959;6967.059446974594;7040.119985001875;7065.43476521877;7099.325564071405;7129.251428600026;2805.593188987626;2896.956545651817;2945.5139901916373;3159.8894038708536;3217.871285148615;664.4518272425172;830.6926437218268;857.9013413457408;867.3668222197705;868.452874688942;26148.34629134249;27832.16503504898;29007.98472053468;29264.268393289913;29554.556681649792;931.8276118918022;1006.597361055569;1010.3988127064532;1054.4411461925192;1062.6599488163713;11554.280631087666;13832.40927293374;13938.024338296222;14181.78727319091;14183.78724319136;607681;607872;610205;610558;613755.6932091444;47978.76010619925;48013.7996550082;48354;48398;48522.02955940861;1189.8036823924128;1354.8712872277117;1378.8621137886132;1394.546772299007;1395.5603984744976;21726.05240710531;22864.199753008565;25208.36979075538;26315.868420657775;26504;32700.83649581737;42977.14045719006;43358;43398.34902476529;43541.346879797464;558521;560477;562449;564082;564296;20360.389188324374;20493;20790.792092078886;23763.881180593988;23896.761032389455;34560;37493.81253093717;38898.80550597229;38962.02594935036;38999.80500097481;32824.68701252019;32945.77518294881;33063;33688.49467257944;33746.32507349888;30278.243043924078;31054.292013939452;33000;33730.49404258889;33874;32437.837810810794;33197.83401082979;33358;33419;33431.66568334286;25683.743162568135;29017;29133.56299655549;29163.541822909196;29777.702222977492;19450.22199112076;21639.45901352479;22648.094076237427;22832.543349132593;22954.770452295263 13:42:05 INFO - PROCESS | 1636 | |2;jquery.html;389.36922186058587;392.26646171659;397.4316727080275;402.7201095238809;421.890308519785;12979.675508112301;14492.637684057903;19435.416937491882;20687.2759453419;20718.964051797408;20321;21095.894520527378;21215.893920530376;21260.681089783666;21443.67834482484;5730.770769169249;5796.942030579693;5854.356020837705;5927.8221653350465;5949.3158286796925;5790.36306006339;5896.380880007602;5899.351071382166;5917.704114794265;5933.851653708649;5750.712464376786;6063.6361818290825;6065.423784740443;6154.784582539612;6160.784372546962;5930.9406905931255;6063.242094738158;6120.663363515005;6125;6161.137440758297;5940.79207227745;5996.070609055603;5997.310309314441;6002.819915402545;6018.608790428608;12803.807942880865;12873.67815804603;13046.347682615926;13229.272390018545;13300.600981970556;2209.4586826227514;2481.0944005438064;2525.6085306777477;2539.5809691400896;2568.730283320262;9310.301727370474;9623;9959.452230127341;10172.44051577163;10254.794904101951;1772.787265528132;1780.5726625609761;1783.8751287409887;1814.3649722597117;1817.4456790678837;110083;129491.35254323762;134826.32586837004;136982;138077;11204.215704900578;11574.18980671345;11949.28088530784;11965.341906195068;11966.760664786656;15342.463013794464;15361.078335299917;15391.307391167424;15444.845551544566;15446.995945263521;8009.4393392463135;8266.504009759434;8332.208440198172;8360.623771930277;8403.327733781405;7848.921510784933;7858.703313953134;7892.9210707893335;8053.15441878606;8057.838843223103;4646.442426908792;4698.765061746899;4727.598154156866;4784.856454306377;4832.154372984784;2009.8090681385245;2047.3141497598472;2080.0847627044054;2081.739782527184;2093.1836583732356;1008.7831116310028;1010.6967909627083;1016.7204018894715;1017.9796404071877;1063.4629512096342;16662.4168154114;18418.447446576625;20169.294074707315;20425.38723838287;20760.58478830445;852.2542775071812;980.5146452505912;1004.829179039565;1057;1067.0396643021188;8710.651573937099;9157.633694652139;10563.940031693899;10728.570857165781;10774.19193560486;427789;667366;680019;681173;688425;115988;140517.29741351432;147334;148687;149410;929.1080562659787;952.3048174832411;967.4098799732158;971.155095067296;972.1590823937244;12673.93663031716;13095.934520327337;17536.47390578285;18191.45425637233;18385.23047509071;54296.18555721588;54503;55467.44532554623;56458.15312770394;56809;76695.61652191704;77575.6727298182;77789;78053;78440.60779696066;6533.575317604341;7987.081485629227;8117.594120294079;8119.75640730779;8167.83664326722;10215.23385746087;12311.938440307742;12421.441035153259;12486.43811028524;12517.56048054449;10563.683089507327;10700.250982431155;10745.731356716145;10747.193960452842;10840.132789376674;9301.604759286161;10498.580056797642;10576.682699519026;10950.835737464104;10977.670669879917;10512.737181570523;10868.239223254297;11132.777344453227;11283.774324513628;11370.033547148585;7912.406569507195;7951.642176101974;8320.500769953822;8415.410921235454;11864.22882512634;10792.244542881923;11667.299962002307;11672.76654466889;11683.240589361665;11698.06415486742 13:42:05 INFO - PROCESS | 1636 | |3;mootools.html;1292.8771766682169;1369.4453746232775;1436.3177490691917;1445.0823726933395;1446.8480809515002;8173.264406203441;8290.95854520728;8573.657053717843;8647.91352086479;8669.739907802768;8496.32029437645;8651.178138076873;8659;8666.39335246533;8686.261667758248;2280.350100221437;2300.9539809203807;2304.6197377432704;2307.607706689867;2311.838171327999;2282.669012993115;2319.014418871981;2320.895559699809;2325.093213646679;2335.509542995973;2234.731832180141;2261.31030035839;2274.7270327560714;2281.327008532487;2282.783135602115;2186.5517568898335;2255.23322070496;2269.3532343282086;2272.7272727272752;2278.7949084582383;1875.728019437174;1906.8188522090518;1990.0248878049797;2298.7011688480557;2302.9654555181687;8263.173682631748;8467.32261419085;8483.490990540526;8569.271611912995;8575.742727718178;5138.794448222066;5289.973550132263;5294.444083371229;5306.389765176977;5307.52232299095;3462.874565766136;3479.382087329404;3485.093875592339;3531.911702207465;3564.055525285801;1202.3627477436971;1205.384784388923;1235.042841797605;1249.575144450882;1254.8431446069242;10729.088027517591;10768.407737574431;10845.783084338269;10855.728606784814;10896.291741036806;4083.5099788025627;4398.780060996937;4454.799534020977;4479.17135329965;4481.35020422037;8676.826463470821;8684.56577171139;8690.652373905099;8709.956450217835;8735.694250701195;4616.261398176276;4758.905451746048;5034.647574669738;5037.093323201859;5079.619028572872;4587.931181032287;4618.35343051973;4726.960068784931;4769.952300476951;4800.639952003614;3608.386574282378;3670.8164591770305;3700.888973330804;3702.7963462009848;3754.962450375516;977.6382738386842;1138.1861968692424;1138.8120960041501;1156.1675593572613;1156.9247998880214;757.1746795992394;794.9744829170305;875.5622188905547;920.3557509743207;924.9352545321898;5244.160934250578;5484.945150548523;5637.943620563742;5657.179708942181;5758.049921762946;500.2346409992717;534.1773668550389;544.5317027356471;558.1822629847293;563.7829435667297;3628.582712987987;3683.650053244937;3726.6459686329754;3732.533433320835;3770.7549009314307;8928.330375221884;9465.432074075577;9620.470874101991;9937.155341795884;10024.849627255597;9841.950790246146;9987.950060249797;10085.899141008496;10107.646232381978;10140;7753.4572579919995;7997.480163789335;8048.959755201186;8050.114487406264;8069.475484093515;5308.362996440452;5473.987312347229;5644;5764.942350576441;5802.622829516068;9005.819883602422;9285.117913798178;9313.953430232805;9330.346875718906;9339.392939458914;9016.549172541478;9356.204722598382;9399.154076133182;9418.387804792666;9440.952795236255;7193;7314.780556583311;7321.450891183289;7335.77992660221;7350.448716346403;7867.8819817703925;7885.64514596833;7925.326347260548;7957.562334071682;7967;6999.545029573062;7101.751438699621;7199.532030418006;7224.530405523624;7285.8178545534665;5963.761449541969;7094.858102838018;7122.18094919091;7185.497015208885;7186.7125314986815;5186.273921651047;6310.842228944313;6395.744170233139;6421.389967953037;6443.420092191725;4931.4328852181025;4999.850004499871;5004.274380214775;5036.874078148076;5106.029854327666;4075.612816782401;4881.7224679929295;4903.95096049035;4943.752812359296;4982.878852258213 13:42:05 INFO - PROCESS | 1636 | |4;prototype.html;321.2434716243247;370.41104643616643;373.3745975491052;377.22103855538313;382.69002108292284;4526.909461810762;4791.832285869995;5547.805826796053;5566.471185237406;5575.916361254584;4963.677360971544;4978.6514943953935;5445.482679145484;5446.754896029678;5452.863678408042;3126;3635.8911831137875;4032.3668914089694;4095.0990782027907;4119.40268661044;3636.5090712753836;4050.5418049502155;4098.364753463218;4140.461739973799;4159.168166366739;3585.2112535242204;3750.6497660842074;3761.6614504694708;3975.185087057146;4132.834686612547;3777.131259810259;4032.9193416131807;4107.383892416132;4115.876523704309;4139.151473947833;2760.5169095408223;3081.9229519261976;3278.3608195902048;3479.6694314040124;3484.5470088888283;4226.36604509323;4396.648268138541;4396.692231543761;5171.00916147286;5301.257823904656;3967.444557761916;4297.70386383949;4669.276262179367;4679.9938013326955;4687.39063921688;5240.711760853152;5378.623496355257;5418.485243901823;5674.319081710221;5711.8000869969355;3104.826621365031;3207.5349074384317;3881.146147847469;3884.6503814656535;3910.8267519743963;5192.143296356135;5665.838503106853;5679.574031947579;5704.458076482728;5991.64050156992;3556.8755093572117;3677.338079145726;3898.9805050974564;3917.196974620196;3944.7633142010995;3967.6032396760093;3976.8210430530694;3990.900227494336;3996.4604778355133;4020.175863947942;4005.348358734732;4381.795006373273;4645.210314246586;4652.906941861212;4693.624510039189;5337.492938170868;5485.314335708036;5602.13166959122;5804.82585522435;5828.473773367136;1180.1267062373954;1275.7512285104501;1288.6649471137384;1296.241698606307;1297.8702129787134;818.9312946604738;871.7359828249064;879.7272845417941;884.9247813935888;889.7286327670123;4368.890777730582;4441.400410944546;4462.188821042722;5060.418051924076;5115.104856650071;4773.112267078437;4952.662781049168;4961.702297862068;5670.554008727748;5744.626599271118;4569.544693923464;4631.166390049756;4815.662903596784;5145.408278047998;5148.56237219833;2749.202731207927;2880.495913215179;2943.3524624582556;2951.2916899943857;2968.317287023953;5198.194279886624;5262.947370526323;5296.761645725952;5500.477454641803;5586.748596313176;4853.4418541867435;5567.192757050205;5710.914336284959;5770.365259821417;5898.76404943806;4591.747453890001;5500.559955203494;6064.514838812796;6121.348314606742;6137.9079313810325;4426.9778651106535;4856.611471082376;5264.499872512105;5278.234655974786;5965.105234214902;4426.336049592587;4883.804647814048;5190.871742585522;5224.582033437392;5678.0915053591225;4781.713097214039;5352.223927530564;5815.82552523425;5989.790357337472;6144.41628045335;3206.2625596112557;3595.1012246938267;3904.219156168721;3930.3121953657424;3943.82252798619;5937.821865344046;5938.565188272787;5945.256842894638;5975;6010.4891084258325;4344.152890186383;4418.270985287391;5378.40837507866;5629.268195134607;5828.970855145697;4737.573618374363;5593.748281327432;5678.403767604342;5698.971505142614;5705.515031222227;5100.648328193106;5235.816746413857;5590.770030593263;5592.315688469476;5610.551155907436;5649.954758369715;5684.431556844448;5709.115087161496;5725.427457254241;5741.368449470639;4914.483979182278;5544.667319960815;5605.439456054362;5616.612939500614;5641.661500309994 13:42:05 INFO - PROCESS | 1636 | |5;yui.html;686.1491750229713;702.546857277056;758.3288789421364;789.1398375770409;796.7330944133715;12215.20601160924;14111.647208819772;14968.251587420626;15018.474353397633;15033.62415939602;13889.02776805624;14960;15728.449504267353;15757.054576725404;16140.596485087879;900.9864852027208;922.3082687984012;925.5742358515057;926.9536523173848;930.5998420679106;697.3863000559501;875.2778957360172;916.8487199612056;929.832630126577;932.7714709896085;946.5030858799151;959.1415682963743;962.0667952086465;962.5139304651176;962.5668449197854;926.8887733471959;928.9767755806091;954.5799848066863;958.7603099225194;959.5681943125586;708.5359089796192;715.7137145141933;717.4009701898868;719.0005891810388;719.7229066809264;12428.13003089784;14465.421383144767;15239.695206095927;15265.007774494734;15291.235438228156;11563.190576659637;12065.276083435023;13646.795298070538;13911.234882081482;14043;11096.308050037693;11268.774624507547;11425.714357141054;11673.416329183507;11675.241109327952;3636.2551346190176;3698.9445158322646;3701.444783282502;3720.274546463441;3825.9808700956596;80185;91734.57161465457;93938.53030734872;94460;94693.52653236758;10483.322668373024;10644.893551064544;10651;10835.133189344833;10948.616798412017;21217.363479095653;21604.13583456644;21626.783732162476;22023.559528809656;22042;6393;7344.816379590553;7429.888551671729;7510.436717246119;7680.615969201517;3708.8331025103935;4075.4294398784195;4133.710640255214;4157.688173387008;4199.643030342394;3492.4586689063235;3551;3589.4615807628543;3600.71194304455;3610.7291953103622;660.5145218264572;662.4917436842334;664.764008776887;670.9127813384326;676.952613317073;643.9066335381343;673.4645956464623;674.9628770417675;695.046918159972;714.7498375568614;9019.549022548847;9472.715818525456;9650.565724542412;9769.071938165864;9790.412575245507;535.8151437754046;660.5409240577849;665.1818263535878;668.8495088605025;679.5073571660605;4856.68431551948;4918.286848406961;5209.53114219722;5315.335583052119;5322.2282768998275;86873.1312686865;87218;88423;89389;91366.08633913708;67173.66413167903;75996;76293;76797;77181.22818771853;67224;71062.64468677728;71426.9285960711;71660.64169679118;72711.63644181745;8710.433821801562;9368.765780855532;9930.346667732781;10326.483675816029;10672.83990740124;35561.822190889914;43769.78115109404;44370;44925;45188.87027824331;55025;56621.71689141528;57605.423945761686;58729.4127058724;60242.19273421804;12819.377341685746;12833.935830320788;12860.549880754503;12882.871171288542;13217.206967581977;12453.630100689099;14081.929590351983;14166.433342666178;14293.499727509487;14380.928095359457;12547.24716517012;13179.341032948507;13321.13412628176;13417.463301467831;13811.240381779098;12846.737178207793;13091.839162502958;13232.933835330761;13253.801192982306;13491.98810089267;11708.88291117078;12949.54676586315;13161.210327380388;13359;13795.034347595572;10549.261551691616;10630.676159757433;10633.415162166155;10647.94676026615;11311.151663625096;8069.919300807152;8288.751337459886;8575.485470871768;8967.820643586962;10493.52779124926 13:42:05 INFO - PROCESS | 1636 | __end_tp_report 13:42:05 INFO - PROCESS | 1636 | __start_cc_report 13:42:05 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,428 13:42:05 INFO - PROCESS | 1636 | __end_cc_report 13:42:05 INFO - PROCESS | 1636 | __startTimestamp1489009325089__endTimestamp 13:42:05 INFO - PROCESS | 1636 | 13:42:05 INFO - PROCESS | 1636 | ------- Summary: start ------- 13:42:05 INFO - PROCESS | 1636 | Number of tests: 6 13:42:05 INFO - PROCESS | 1636 | 13:42:05 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:5633.62 Median:5892.15 stddev:638.25 (10.8%) stddev-sans-first:613.02 13:42:05 INFO - PROCESS | 1636 | Values: 3284.5 3361.4 3367.4 3388.7 3402.9 4292.4 4960.7 5496.5 5583.4 5676.6 4728.6 5526.2 5590.4 5691.7 5705.5 5062.6 5071.5 5145.2 5204.0 5243.1 5130.2 5143.4 5208.9 5260.1 5269.4 5010.6 5228.8 5232.2 5268.6 5301.2 5020.0 5084.3 5118.0 5125.8 5143.8 5147.8 5178.7 5180.8 5181.6 5203.2 5249.8 5651.2 5705.8 5709.6 5716.0 6027.7 6076.9 6156.8 6198.1 6200.7 5331.5 6135.5 6157.1 6166.2 6198.3 5825.1 5843.8 5848.7 5851.7 5871.8 6096.5 6206.5 6218.7 6226.5 6247.9 5925.1 5955.3 5963.0 6127.4 6175.2 6117.4 6143.6 6144.7 6146.8 6199.3 6031.5 6047.7 6053.8 6060.1 6221.2 6115.6 6145.6 6189.4 6203.2 6257.2 4806.1 4957.2 4959.0 4968.0 4975.2 4320.8 4343.1 4367.4 4447.1 4466.1 4989.7 5679.3 5949.5 5954.8 5997.2 6177.4 6227.4 6233.7 6242.7 6251.6 5601.9 5926.1 5940.5 5946.3 5993.8 5258.8 5278.5 5315.1 5316.6 5341.5 6109.5 6113.5 6146.4 6146.5 6153.1 5191.9 6111.6 6134.6 6139.0 6146.1 5567.6 6137.3 6143.2 6147.5 6199.9 5267.7 6147.1 6177.8 6181.8 6185.8 5113.5 5203.6 6202.0 6205.5 6210.7 5571.6 5766.1 5834.4 6055.2 6101.7 5901.3 6005.8 6081.7 6103.3 6118.1 5298.6 5312.8 5687.3 5949.9 5982.5 5874.2 5998.5 6081.2 6091.2 6118.0 5686.3 5892.1 5966.9 5986.3 6003.3 13:42:05 INFO - PROCESS | 1636 | 13:42:05 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:64186.32 Median:3730.58 stddev:145056.44 (3888.3%) stddev-sans-first:145384.43 13:42:05 INFO - PROCESS | 1636 | Values: 450.7 467.8 512.6 513.8 538.7 33901.8 38109.8 39475.6 39594.6 39597.0 35225.8 36185.6 36291.3 37534.6 37829.0 9210.4 9404.1 9421.3 9438.2 9441.5 9194.4 9262.3 9303.2 9319.2 9362.4 9341.3 9366.0 9375.7 9387.9 9404.3 9259.9 9288.8 9365.1 9375.1 9400.4 9258.7 9260.1 9291.8 9350.0 9361.2 36008.8 36039.8 36278.6 36307.0 36368.6 3727.9 3733.3 3806.1 3807.2 3970.6 21209.0 27327.7 28413.6 28564.7 28605.1 2994.9 3125.5 3177.5 3239.2 3240.9 429217.0 432971.0 435017.0 436230.0 446151.3 40734.4 52115.0 52989.7 53176.5 56067.7 56035.4 56407.7 56501.7 56518.7 56561.4 14193.4 14218.1 14388.9 14421.6 14422.9 11050.4 11102.1 11120.9 11141.9 11145.6 6967.1 7040.1 7065.4 7099.3 7129.3 2805.6 2897.0 2945.5 3159.9 3217.9 664.5 830.7 857.9 867.4 868.5 26148.3 27832.2 29008.0 29264.3 29554.6 931.8 1006.6 1010.4 1054.4 1062.7 11554.3 13832.4 13938.0 14181.8 14183.8 607681.0 607872.0 610205.0 610558.0 613755.7 47978.8 48013.8 48354.0 48398.0 48522.0 1189.8 1354.9 1378.9 1394.5 1395.6 21726.1 22864.2 25208.4 26315.9 26504.0 32700.8 42977.1 43358.0 43398.3 43541.3 558521.0 560477.0 562449.0 564082.0 564296.0 20360.4 20493.0 20790.8 23763.9 23896.8 34560.0 37493.8 38898.8 38962.0 38999.8 32824.7 32945.8 33063.0 33688.5 33746.3 30278.2 31054.3 33000.0 33730.5 33874.0 32437.8 33197.8 33358.0 33419.0 33431.7 25683.7 29017.0 29133.6 29163.5 29777.7 19450.2 21639.5 22648.1 22832.5 22954.8 13:42:05 INFO - PROCESS | 1636 | 13:42:05 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:36093.39 Median:21238.29 stddev:106765.06 (502.7%) stddev-sans-first:107030.91 13:42:05 INFO - PROCESS | 1636 | Values: 389.4 392.3 397.4 402.7 421.9 12979.7 14492.6 19435.4 20687.3 20719.0 20321.0 21095.9 21215.9 21260.7 21443.7 5730.8 5796.9 5854.4 5927.8 5949.3 5790.4 5896.4 5899.4 5917.7 5933.9 5750.7 6063.6 6065.4 6154.8 6160.8 5930.9 6063.2 6120.7 6125.0 6161.1 5940.8 5996.1 5997.3 6002.8 6018.6 12803.8 12873.7 13046.3 13229.3 13300.6 2209.5 2481.1 2525.6 2539.6 2568.7 9310.3 9623.0 9959.5 10172.4 10254.8 1772.8 1780.6 1783.9 1814.4 1817.4 110083.0 129491.4 134826.3 136982.0 138077.0 11204.2 11574.2 11949.3 11965.3 11966.8 15342.5 15361.1 15391.3 15444.8 15447.0 8009.4 8266.5 8332.2 8360.6 8403.3 7848.9 7858.7 7892.9 8053.2 8057.8 4646.4 4698.8 4727.6 4784.9 4832.2 2009.8 2047.3 2080.1 2081.7 2093.2 1008.8 1010.7 1016.7 1018.0 1063.5 16662.4 18418.4 20169.3 20425.4 20760.6 852.3 980.5 1004.8 1057.0 1067.0 8710.7 9157.6 10563.9 10728.6 10774.2 427789.0 667366.0 680019.0 681173.0 688425.0 115988.0 140517.3 147334.0 148687.0 149410.0 929.1 952.3 967.4 971.2 972.2 12673.9 13095.9 17536.5 18191.5 18385.2 54296.2 54503.0 55467.4 56458.2 56809.0 76695.6 77575.7 77789.0 78053.0 78440.6 6533.6 7987.1 8117.6 8119.8 8167.8 10215.2 12311.9 12421.4 12486.4 12517.6 10563.7 10700.3 10745.7 10747.2 10840.1 9301.6 10498.6 10576.7 10950.8 10977.7 10512.7 10868.2 11132.8 11283.8 11370.0 7912.4 7951.6 8320.5 8415.4 11864.2 10792.2 11667.3 11672.8 11683.2 11698.1 13:42:05 INFO - PROCESS | 1636 | 13:42:05 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:5393.46 Median:5215.22 stddev:2991.27 (57.4%) stddev-sans-first:2983.79 13:42:05 INFO - PROCESS | 1636 | Values: 1292.9 1369.4 1436.3 1445.1 1446.8 8173.3 8291.0 8573.7 8647.9 8669.7 8496.3 8651.2 8659.0 8666.4 8686.3 2280.4 2301.0 2304.6 2307.6 2311.8 2282.7 2319.0 2320.9 2325.1 2335.5 2234.7 2261.3 2274.7 2281.3 2282.8 2186.6 2255.2 2269.4 2272.7 2278.8 1875.7 1906.8 1990.0 2298.7 2303.0 8263.2 8467.3 8483.5 8569.3 8575.7 5138.8 5290.0 5294.4 5306.4 5307.5 3462.9 3479.4 3485.1 3531.9 3564.1 1202.4 1205.4 1235.0 1249.6 1254.8 10729.1 10768.4 10845.8 10855.7 10896.3 4083.5 4398.8 4454.8 4479.2 4481.4 8676.8 8684.6 8690.7 8710.0 8735.7 4616.3 4758.9 5034.6 5037.1 5079.6 4587.9 4618.4 4727.0 4770.0 4800.6 3608.4 3670.8 3700.9 3702.8 3755.0 977.6 1138.2 1138.8 1156.2 1156.9 757.2 795.0 875.6 920.4 924.9 5244.2 5484.9 5637.9 5657.2 5758.0 500.2 534.2 544.5 558.2 563.8 3628.6 3683.7 3726.6 3732.5 3770.8 8928.3 9465.4 9620.5 9937.2 10024.8 9842.0 9988.0 10085.9 10107.6 10140.0 7753.5 7997.5 8049.0 8050.1 8069.5 5308.4 5474.0 5644.0 5764.9 5802.6 9005.8 9285.1 9314.0 9330.3 9339.4 9016.5 9356.2 9399.2 9418.4 9441.0 7193.0 7314.8 7321.5 7335.8 7350.4 7867.9 7885.6 7925.3 7957.6 7967.0 6999.5 7101.8 7199.5 7224.5 7285.8 5963.8 7094.9 7122.2 7185.5 7186.7 5186.3 6310.8 6395.7 6421.4 6443.4 4931.4 4999.9 5004.3 5036.9 5106.0 4075.6 4881.7 4904.0 4943.8 4982.9 13:42:05 INFO - PROCESS | 1636 | 13:42:05 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4462.47 Median:4870.21 stddev:1390.70 (28.6%) stddev-sans-first:1359.42 13:42:05 INFO - PROCESS | 1636 | Values: 321.2 370.4 373.4 377.2 382.7 4526.9 4791.8 5547.8 5566.5 5575.9 4963.7 4978.7 5445.5 5446.8 5452.9 3126.0 3635.9 4032.4 4095.1 4119.4 3636.5 4050.5 4098.4 4140.5 4159.2 3585.2 3750.6 3761.7 3975.2 4132.8 3777.1 4032.9 4107.4 4115.9 4139.2 2760.5 3081.9 3278.4 3479.7 3484.5 4226.4 4396.6 4396.7 5171.0 5301.3 3967.4 4297.7 4669.3 4680.0 4687.4 5240.7 5378.6 5418.5 5674.3 5711.8 3104.8 3207.5 3881.1 3884.7 3910.8 5192.1 5665.8 5679.6 5704.5 5991.6 3556.9 3677.3 3899.0 3917.2 3944.8 3967.6 3976.8 3990.9 3996.5 4020.2 4005.3 4381.8 4645.2 4652.9 4693.6 5337.5 5485.3 5602.1 5804.8 5828.5 1180.1 1275.8 1288.7 1296.2 1297.9 818.9 871.7 879.7 884.9 889.7 4368.9 4441.4 4462.2 5060.4 5115.1 4773.1 4952.7 4961.7 5670.6 5744.6 4569.5 4631.2 4815.7 5145.4 5148.6 2749.2 2880.5 2943.4 2951.3 2968.3 5198.2 5262.9 5296.8 5500.5 5586.7 4853.4 5567.2 5710.9 5770.4 5898.8 4591.7 5500.6 6064.5 6121.3 6137.9 4427.0 4856.6 5264.5 5278.2 5965.1 4426.3 4883.8 5190.9 5224.6 5678.1 4781.7 5352.2 5815.8 5989.8 6144.4 3206.3 3595.1 3904.2 3930.3 3943.8 5937.8 5938.6 5945.3 5975.0 6010.5 4344.2 4418.3 5378.4 5629.3 5829.0 4737.6 5593.7 5678.4 5699.0 5705.5 5100.6 5235.8 5590.8 5592.3 5610.6 5650.0 5684.4 5709.1 5725.4 5741.4 4914.5 5544.7 5605.4 5616.6 5641.7 13:42:05 INFO - PROCESS | 1636 | 13:42:05 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:18407.03 Median:5262.43 stddev:25172.97 (478.4%) stddev-sans-first:25208.41 13:42:05 INFO - PROCESS | 1636 | Values: 686.1 702.5 758.3 789.1 796.7 12215.2 14111.6 14968.3 15018.5 15033.6 13889.0 14960.0 15728.4 15757.1 16140.6 901.0 922.3 925.6 927.0 930.6 697.4 875.3 916.8 929.8 932.8 946.5 959.1 962.1 962.5 962.6 926.9 929.0 954.6 958.8 959.6 708.5 715.7 717.4 719.0 719.7 12428.1 14465.4 15239.7 15265.0 15291.2 11563.2 12065.3 13646.8 13911.2 14043.0 11096.3 11268.8 11425.7 11673.4 11675.2 3636.3 3698.9 3701.4 3720.3 3826.0 80185.0 91734.6 93938.5 94460.0 94693.5 10483.3 10644.9 10651.0 10835.1 10948.6 21217.4 21604.1 21626.8 22023.6 22042.0 6393.0 7344.8 7429.9 7510.4 7680.6 3708.8 4075.4 4133.7 4157.7 4199.6 3492.5 3551.0 3589.5 3600.7 3610.7 660.5 662.5 664.8 670.9 677.0 643.9 673.5 675.0 695.0 714.7 9019.5 9472.7 9650.6 9769.1 9790.4 535.8 660.5 665.2 668.8 679.5 4856.7 4918.3 5209.5 5315.3 5322.2 86873.1 87218.0 88423.0 89389.0 91366.1 67173.7 75996.0 76293.0 76797.0 77181.2 67224.0 71062.6 71426.9 71660.6 72711.6 8710.4 9368.8 9930.3 10326.5 10672.8 35561.8 43769.8 44370.0 44925.0 45188.9 55025.0 56621.7 57605.4 58729.4 60242.2 12819.4 12833.9 12860.5 12882.9 13217.2 12453.6 14081.9 14166.4 14293.5 14380.9 12547.2 13179.3 13321.1 13417.5 13811.2 12846.7 13091.8 13232.9 13253.8 13492.0 11708.9 12949.5 13161.2 13359.0 13795.0 10549.3 10630.7 10633.4 10647.9 11311.2 8069.9 8288.8 8575.5 8967.8 10493.5 13:42:05 INFO - PROCESS | 1636 | -------- Summary: end -------- 13:42:05 INFO - PROCESS | 1636 | 13:42:05 INFO - TEST-INFO | 1636: exit 0 13:42:05 INFO - TEST-OK | dromaeo_css | took 1116886ms 13:42:05 INFO - TEST-START | kraken 13:42:05 INFO - operating with platform_type : mac_ 13:42:05 INFO - Initialising browser for kraken test... 13:42:05 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmpjMx8tR/profile http://localhost:49239/getInfo.html) 13:42:08 INFO - PROCESS | 1649 | 2017-03-08 13:42:08.382 plugin-container[1650:10363] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 13:42:08 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:42:08 INFO - PROCESS | 1649 | 2017-03-08 13:42:08.385 plugin-container[1650:10363] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4723, name = 'com.apple.CFPasteboardClient' 13:42:08 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:42:08 INFO - PROCESS | 1649 | 2017-03-08 13:42:08.385 plugin-container[1650:10363] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:42:08 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 13:42:08 INFO - PROCESS | 1649 | colorDepth:24 13:42:08 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 13:42:08 INFO - PROCESS | 1649 | __metrics 13:42:08 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 13:42:09 INFO - TEST-INFO | 1649: exit 0 13:42:09 INFO - Browser initialized. 13:42:09 INFO - Running cycle 1/1 for kraken test... 13:42:09 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmpjMx8tR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:42:11 INFO - PROCESS | 1651 | 2017-03-08 13:42:11.841 plugin-container[1652:10481] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 13:42:11 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:42:11 INFO - PROCESS | 1651 | 2017-03-08 13:42:11.844 plugin-container[1652:10481] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4817, name = 'com.apple.CFPasteboardClient' 13:42:11 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:42:11 INFO - PROCESS | 1651 | 2017-03-08 13:42:11.844 plugin-container[1652:10481] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:42:21 INFO - PROCESS | 1651 | RSS: Main: 233029632 13:42:21 INFO - PROCESS | 1651 | 13:42:24 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-beat-detection) 13:42:25 INFO - PROCESS | 1651 | RSS: Main: 247611392 13:42:25 INFO - PROCESS | 1651 | 13:42:28 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-dft) 13:42:28 INFO - PROCESS | 1651 | RSS: Main: 255590400 13:42:28 INFO - PROCESS | 1651 | 13:42:32 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-fft) 13:42:33 INFO - PROCESS | 1651 | RSS: Main: 258297856 13:42:33 INFO - PROCESS | 1651 | 13:42:35 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-oscillator) 13:42:35 INFO - PROCESS | 1651 | RSS: Main: 260571136 13:42:35 INFO - PROCESS | 1651 | 13:42:37 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:42:38 INFO - PROCESS | 1651 | RSS: Main: 260894720 13:42:38 INFO - PROCESS | 1651 | 13:42:41 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-darkroom) 13:42:42 INFO - PROCESS | 1651 | RSS: Main: 266952704 13:42:42 INFO - PROCESS | 1651 | 13:42:45 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-desaturate) 13:42:45 INFO - PROCESS | 1651 | RSS: Main: 266002432 13:42:45 INFO - PROCESS | 1651 | 13:42:48 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49239/tests/kraken/driver.html?testName=json-parse-financial) 13:42:49 INFO - PROCESS | 1651 | RSS: Main: 266403840 13:42:49 INFO - PROCESS | 1651 | 13:42:50 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49239/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:42:51 INFO - PROCESS | 1651 | RSS: Main: 265568256 13:42:51 INFO - PROCESS | 1651 | 13:42:53 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:42:54 INFO - PROCESS | 1651 | RSS: Main: 265609216 13:42:54 INFO - PROCESS | 1651 | 13:42:56 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:42:57 INFO - PROCESS | 1651 | RSS: Main: 265650176 13:42:57 INFO - PROCESS | 1651 | 13:43:00 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:43:00 INFO - PROCESS | 1651 | RSS: Main: 265900032 13:43:00 INFO - PROCESS | 1651 | 13:43:03 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:43:04 INFO - PROCESS | 1651 | RSS: Main: 265809920 13:43:04 INFO - PROCESS | 1651 | 13:43:06 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49239/tests/kraken/driver.html?testName=ai-astar) 13:43:06 INFO - PROCESS | 1651 | RSS: Main: 267644928 13:43:06 INFO - PROCESS | 1651 | 13:43:06 INFO - PROCESS | 1651 | __start_tp_report 13:43:06 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 13:43:06 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 13:43:06 INFO - PROCESS | 1651 | |i|pagename|runs| 13:43:06 INFO - PROCESS | 1651 | |0;ai-astar;106;115;115;117;119;134;175;156;117;118 13:43:06 INFO - PROCESS | 1651 | |1;audio-beat-detection;159;147;136;158;114;116;117;116;183;147 13:43:06 INFO - PROCESS | 1651 | |2;audio-dft;262;247;230;200;199;203;196;198;197;199 13:43:06 INFO - PROCESS | 1651 | |3;audio-fft;106;108;74;86;61;61;60;71;68;75 13:43:06 INFO - PROCESS | 1651 | |4;audio-oscillator;89;72;87;72;71;74;100;61;84;106 13:43:06 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;88;90;89;108;135;137;93;135;137;96 13:43:06 INFO - PROCESS | 1651 | |6;imaging-darkroom;96;97;97;97;96;96;97;95;97;98 13:43:06 INFO - PROCESS | 1651 | |7;imaging-desaturate;94;93;93;95;94;92;100;123;92;92 13:43:06 INFO - PROCESS | 1651 | |8;json-parse-financial;65;64;65;65;63;64;66;65;63;63 13:43:06 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;73;76;98;114;109;68;68;73;73;73 13:43:06 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;89;88;87;88;89;91;90;90;90;93 13:43:06 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;155;149;136;143;147;145;162;150;187;146 13:43:06 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;180;179;178;219;181;162;173;187;157;236 13:43:06 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;68;86;77;70;68;67;68;68;66;70 13:43:06 INFO - PROCESS | 1651 | __end_tp_report 13:43:06 INFO - PROCESS | 1651 | __start_cc_report 13:43:06 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,1004 13:43:06 INFO - PROCESS | 1651 | __end_cc_report 13:43:06 INFO - PROCESS | 1651 | __startTimestamp1489009386540__endTimestamp 13:43:06 INFO - PROCESS | 1651 | 13:43:06 INFO - PROCESS | 1651 | ------- Summary: start ------- 13:43:06 INFO - PROCESS | 1651 | Number of tests: 14 13:43:06 INFO - PROCESS | 1651 | 13:43:06 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:127.20 Median:118.50 stddev:21.78 (18.4%) stddev-sans-first:21.70 13:43:06 INFO - PROCESS | 1651 | Values: 106.0 115.0 115.0 117.0 119.0 134.0 175.0 156.0 117.0 118.0 13:43:06 INFO - PROCESS | 1651 | 13:43:06 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:139.30 Median:147.00 stddev:23.57 (16.0%) stddev-sans-first:23.90 13:43:06 INFO - PROCESS | 1651 | Values: 159.0 147.0 136.0 158.0 114.0 116.0 117.0 116.0 183.0 147.0 13:43:06 INFO - PROCESS | 1651 | 13:43:06 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:213.10 Median:201.50 stddev:24.21 (12.0%) stddev-sans-first:18.10 13:43:06 INFO - PROCESS | 1651 | Values: 262.0 247.0 230.0 200.0 199.0 203.0 196.0 198.0 197.0 199.0 13:43:06 INFO - PROCESS | 1651 | 13:43:06 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:77.00 Median:69.50 stddev:17.68 (25.4%) stddev-sans-first:15.33 13:43:06 INFO - PROCESS | 1651 | Values: 106.0 108.0 74.0 86.0 61.0 61.0 60.0 71.0 68.0 75.0 13:43:06 INFO - PROCESS | 1651 | 13:43:06 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:81.60 Median:73.00 stddev:14.15 (19.4%) stddev-sans-first:14.75 13:43:06 INFO - PROCESS | 1651 | Values: 89.0 72.0 87.0 72.0 71.0 74.0 100.0 61.0 84.0 106.0 13:43:06 INFO - PROCESS | 1651 | 13:43:06 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:110.80 Median:88.50 stddev:22.40 (25.3%) stddev-sans-first:22.19 13:43:06 INFO - PROCESS | 1651 | Values: 88.0 90.0 89.0 108.0 135.0 137.0 93.0 135.0 137.0 96.0 13:43:06 INFO - PROCESS | 1651 | 13:43:06 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:96.60 Median:97.00 stddev:0.84 (0.9%) stddev-sans-first:0.87 13:43:06 INFO - PROCESS | 1651 | Values: 96.0 97.0 97.0 97.0 96.0 96.0 97.0 95.0 97.0 98.0 13:43:06 INFO - PROCESS | 1651 | 13:43:06 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:96.80 Median:93.00 stddev:9.51 (10.2%) stddev-sans-first:10.03 13:43:06 INFO - PROCESS | 1651 | Values: 94.0 93.0 93.0 95.0 94.0 92.0 100.0 123.0 92.0 92.0 13:43:06 INFO - PROCESS | 1651 | 13:43:06 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:65.00 stddev:1.06 (1.6%) stddev-sans-first:1.09 13:43:06 INFO - PROCESS | 1651 | Values: 65.0 64.0 65.0 65.0 63.0 64.0 66.0 65.0 63.0 63.0 13:43:06 INFO - PROCESS | 1651 | 13:43:06 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:82.50 Median:73.00 stddev:17.51 (24.0%) stddev-sans-first:18.23 13:43:06 INFO - PROCESS | 1651 | Values: 73.0 76.0 98.0 114.0 109.0 68.0 68.0 73.0 73.0 73.0 13:43:06 INFO - PROCESS | 1651 | 13:43:06 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:89.50 Median:90.00 stddev:1.72 (1.9%) stddev-sans-first:1.81 13:43:06 INFO - PROCESS | 1651 | Values: 89.0 88.0 87.0 88.0 89.0 91.0 90.0 90.0 90.0 93.0 13:43:06 INFO - PROCESS | 1651 | 13:43:06 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:152.00 Median:149.50 stddev:14.12 (9.4%) stddev-sans-first:14.93 13:43:06 INFO - PROCESS | 1651 | Values: 155.0 149.0 136.0 143.0 147.0 145.0 162.0 150.0 187.0 146.0 13:43:06 INFO - PROCESS | 1651 | 13:43:06 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.20 Median:180.50 stddev:24.37 (13.5%) stddev-sans-first:25.77 13:43:06 INFO - PROCESS | 1651 | Values: 180.0 179.0 178.0 219.0 181.0 162.0 173.0 187.0 157.0 236.0 13:43:06 INFO - PROCESS | 1651 | 13:43:06 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.80 Median:69.00 stddev:6.14 (8.9%) stddev-sans-first:6.43 13:43:06 INFO - PROCESS | 1651 | Values: 68.0 86.0 77.0 70.0 68.0 67.0 68.0 68.0 66.0 70.0 13:43:06 INFO - PROCESS | 1651 | -------- Summary: end -------- 13:43:06 INFO - PROCESS | 1651 | 13:43:07 INFO - TEST-INFO | 1651: exit 0 13:43:07 INFO - TEST-OK | kraken | took 61739ms 13:43:07 INFO - SUITE-END | took 1178s 13:43:07 INFO - Completed test suite (00:19:38) 13:43:07 INFO - javascript benchmark 13:43:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6972.803662710091, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3284.458064419371, 3361.428557145285, 3367.444371678672, 3388.7289016878653, 3402.897913062609, 4292.399064131024, 4960.710215344014, 5496.532794712454, 5583.35791383991, 5676.631018983763, 4728.621710263179, 5526.171074338851, 5590.385057643656, 5691.686957217351, 5705.4579814917515, 5062.594992400608, 5071.518205770446, 5145.228215767646, 5203.98522288154, 5243.108671525831, 5130.204818253177, 5143.408508021569, 5208.921866172011, 5260.132078207111, 5269.394019687747, 5010.574101201402, 5228.843134705965, 5232.189010703347, 5268.631195816294, 5301.151815709487, 5019.974900125512, 5084.262781896604, 5117.950820081875, 5125.769340379692, 5143.8456846294675, 5147.845564633067, 5178.741062946838, 5180.792768289264, 5181.611379146579, 5203.19350500673, 5249.816256431007, 5651.208830763697, 5705.800296989627, 5709.600327977043, 5715.971420142914, 6027.728752206117, 6076.939230607726, 6156.815295541141, 6198.132261483396, 6200.658963756991, 5331.546818520431, 6135.508280839769, 6157.138000679908, 6166.229221347332, 6198.256209254873, 5825.097109947922, 5843.795467158714, 5848.707564621752, 5851.678157701324, 5871.794487192928, 6096.512279017668, 6206.534509911775, 6218.689065546704, 6226.501879849601, 6247.937520624736, 5925.111233315038, 5955.3151387590115, 5963.0, 6127.356627554132, 6175.228096487939, 6117.388261173847, 6143.603994442171, 6144.740328232701, 6146.846328841725, 6199.287081985539, 6031.487323577545, 6047.667378294148, 6053.757849685963, 6060.121282414026, 6221.191245138193, 6115.575014303891, 6145.617236121837, 6189.412005859436, 6203.16257305258, 6257.155284036688, 4806.110869489155, 4957.157283261854, 4958.958618690043, 4967.975160124176, 4975.203967365204, 4320.805563749639, 4343.0662407581885, 4367.410399596078, 4447.066116115652, 4466.129104824528, 4989.725565093955, 5679.346875109332, 5949.494292985059, 5954.779270249588, 5997.160397544348, 6177.402847745927, 6227.377262273736, 6233.688315584202, 6242.719077641517, 6251.624902505864, 5601.943980560224, 5926.140709597171, 5940.495057920039, 5946.316173640001, 5993.760249590055, 5258.789648414097, 5278.4721527846905, 5315.096433606296, 5316.627836051439, 5341.5459685927135, 6109.4653969264045, 6113.506711826627, 6146.385361463817, 6146.53900957439, 6153.07703844409, 5191.870203244949, 6111.602745821508, 6134.619710565087, 6139.048447490646, 6146.139540464249, 5567.582431317586, 6137.263528376623, 6143.232095988002, 6147.538934579835, 6199.938000620116, 5267.73661316925, 6147.108669242845, 6177.81466556004, 6181.81454556364, 6185.783497577563, 5113.463086376026, 5203.635745497779, 6202.0, 6205.47253483459, 6210.689465526796, 5571.637843540157, 5766.106253530709, 5834.445727655866, 6055.212822333068, 6101.725422356093, 5901.29184497863, 6005.819825405245, 6081.726322315595, 6103.298120715996, 6118.131909153847, 5298.602604804578, 5312.787488500417, 5687.317521897398, 5949.851253718519, 5982.521398288213, 5874.236349274738, 5998.4601385875485, 6081.209442772411, 6091.208142941567, 6117.990531562243, 5686.317641883001, 5892.145638882424, 5966.925953328442, 5986.281646202484, 6003.279606447254], "value": 5595.83759923028, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [450.65299719216193, 467.7544289248144, 512.5873671694285, 513.809890340574, 538.7440965541366, 33901.83049084754, 38109.809450952744, 39475.60524394755, 39594.604053959454, 39597.0, 35225.82387088061, 36185.55257789692, 36291.2741745165, 37534.62465375345, 37829.0, 9210.355275130743, 9404.059594040575, 9421.340506164572, 9438.197753190987, 9441.480718560477, 9194.448333100036, 9262.259019278476, 9303.162715355616, 9319.161275485205, 9362.438253704799, 9341.252699784001, 9365.953170234174, 9375.718728438158, 9387.859182112274, 9404.341696081243, 9259.861102083474, 9288.767780805534, 9365.063493650649, 9375.10936461035, 9400.388974716621, 9258.72223833286, 9260.120288572574, 9291.814163716688, 9350.0, 9361.15749582534, 36008.81995590005, 36039.81980090109, 36278.637213628055, 36307.0, 36368.63631363679, 3727.8881633551036, 3733.2720119576693, 3806.0865392305923, 3807.2195199983935, 3970.583088775666, 21209.0, 27327.726722732717, 28413.57379639307, 28564.71435285662, 28605.14184574466, 2994.910152695401, 3125.484295091307, 3177.459831828595, 3239.2225865792275, 3240.91897702557, 429217.0, 432971.0, 435017.0, 436230.0, 446151.30773038755, 40734.38898416526, 52114.95770084577, 52989.7350513245, 53176.46823531792, 56067.71966140225, 56035.43964560302, 56407.71796141075, 56501.71749141228, 56518.7174064127, 56561.434385655615, 14193.361298741582, 14218.075825071337, 14388.928055359656, 14421.639459013399, 14422.927885360716, 11050.392228427514, 11102.056325212287, 11120.888791111985, 11141.888581114246, 11145.554177832959, 6967.059446974594, 7040.119985001875, 7065.43476521877, 7099.325564071405, 7129.251428600026, 2805.593188987626, 2896.956545651817, 2945.5139901916373, 3159.8894038708536, 3217.871285148615, 664.4518272425172, 830.6926437218268, 857.9013413457408, 867.3668222197705, 868.452874688942, 26148.34629134249, 27832.16503504898, 29007.98472053468, 29264.268393289913, 29554.556681649792, 931.8276118918022, 1006.597361055569, 1010.3988127064532, 1054.4411461925192, 1062.6599488163713, 11554.280631087666, 13832.40927293374, 13938.024338296222, 14181.78727319091, 14183.78724319136, 607681.0, 607872.0, 610205.0, 610558.0, 613755.6932091444, 47978.76010619925, 48013.7996550082, 48354.0, 48398.0, 48522.02955940861, 1189.8036823924128, 1354.8712872277117, 1378.8621137886132, 1394.546772299007, 1395.5603984744976, 21726.05240710531, 22864.199753008565, 25208.36979075538, 26315.868420657775, 26504.0, 32700.83649581737, 42977.14045719006, 43358.0, 43398.34902476529, 43541.346879797464, 558521.0, 560477.0, 562449.0, 564082.0, 564296.0, 20360.389188324374, 20493.0, 20790.792092078886, 23763.881180593988, 23896.761032389455, 34560.0, 37493.81253093717, 38898.80550597229, 38962.02594935036, 38999.80500097481, 32824.68701252019, 32945.77518294881, 33063.0, 33688.49467257944, 33746.32507349888, 30278.243043924078, 31054.292013939452, 33000.0, 33730.49404258889, 33874.0, 32437.837810810794, 33197.83401082979, 33358.0, 33419.0, 33431.66568334286, 25683.743162568135, 29017.0, 29133.56299655549, 29163.541822909196, 29777.702222977492, 19450.22199112076, 21639.45901352479, 22648.094076237427, 22832.543349132593, 22954.770452295263], "value": 17232.501548152486, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.36922186058587, 392.26646171659, 397.4316727080275, 402.7201095238809, 421.890308519785, 12979.675508112301, 14492.637684057903, 19435.416937491882, 20687.2759453419, 20718.964051797408, 20321.0, 21095.894520527378, 21215.893920530376, 21260.681089783666, 21443.67834482484, 5730.770769169249, 5796.942030579693, 5854.356020837705, 5927.8221653350465, 5949.3158286796925, 5790.36306006339, 5896.380880007602, 5899.351071382166, 5917.704114794265, 5933.851653708649, 5750.712464376786, 6063.6361818290825, 6065.423784740443, 6154.784582539612, 6160.784372546962, 5930.9406905931255, 6063.242094738158, 6120.663363515005, 6125.0, 6161.137440758297, 5940.79207227745, 5996.070609055603, 5997.310309314441, 6002.819915402545, 6018.608790428608, 12803.807942880865, 12873.67815804603, 13046.347682615926, 13229.272390018545, 13300.600981970556, 2209.4586826227514, 2481.0944005438064, 2525.6085306777477, 2539.5809691400896, 2568.730283320262, 9310.301727370474, 9623.0, 9959.452230127341, 10172.44051577163, 10254.794904101951, 1772.787265528132, 1780.5726625609761, 1783.8751287409887, 1814.3649722597117, 1817.4456790678837, 110083.0, 129491.35254323762, 134826.32586837004, 136982.0, 138077.0, 11204.215704900578, 11574.18980671345, 11949.28088530784, 11965.341906195068, 11966.760664786656, 15342.463013794464, 15361.078335299917, 15391.307391167424, 15444.845551544566, 15446.995945263521, 8009.4393392463135, 8266.504009759434, 8332.208440198172, 8360.623771930277, 8403.327733781405, 7848.921510784933, 7858.703313953134, 7892.9210707893335, 8053.15441878606, 8057.838843223103, 4646.442426908792, 4698.765061746899, 4727.598154156866, 4784.856454306377, 4832.154372984784, 2009.8090681385245, 2047.3141497598472, 2080.0847627044054, 2081.739782527184, 2093.1836583732356, 1008.7831116310028, 1010.6967909627083, 1016.7204018894715, 1017.9796404071877, 1063.4629512096342, 16662.4168154114, 18418.447446576625, 20169.294074707315, 20425.38723838287, 20760.58478830445, 852.2542775071812, 980.5146452505912, 1004.829179039565, 1057.0, 1067.0396643021188, 8710.651573937099, 9157.633694652139, 10563.940031693899, 10728.570857165781, 10774.19193560486, 427789.0, 667366.0, 680019.0, 681173.0, 688425.0, 115988.0, 140517.29741351432, 147334.0, 148687.0, 149410.0, 929.1080562659787, 952.3048174832411, 967.4098799732158, 971.155095067296, 972.1590823937244, 12673.93663031716, 13095.934520327337, 17536.47390578285, 18191.45425637233, 18385.23047509071, 54296.18555721588, 54503.0, 55467.44532554623, 56458.15312770394, 56809.0, 76695.61652191704, 77575.6727298182, 77789.0, 78053.0, 78440.60779696066, 6533.575317604341, 7987.081485629227, 8117.594120294079, 8119.75640730779, 8167.83664326722, 10215.23385746087, 12311.938440307742, 12421.441035153259, 12486.43811028524, 12517.56048054449, 10563.683089507327, 10700.250982431155, 10745.731356716145, 10747.193960452842, 10840.132789376674, 9301.604759286161, 10498.580056797642, 10576.682699519026, 10950.835737464104, 10977.670669879917, 10512.737181570523, 10868.239223254297, 11132.777344453227, 11283.774324513628, 11370.033547148585, 7912.406569507195, 7951.642176101974, 8320.500769953822, 8415.410921235454, 11864.22882512634, 10792.244542881923, 11667.299962002307, 11672.76654466889, 11683.240589361665, 11698.06415486742], "value": 9451.898816773639, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1292.8771766682169, 1369.4453746232775, 1436.3177490691917, 1445.0823726933395, 1446.8480809515002, 8173.264406203441, 8290.95854520728, 8573.657053717843, 8647.91352086479, 8669.739907802768, 8496.32029437645, 8651.178138076873, 8659.0, 8666.39335246533, 8686.261667758248, 2280.350100221437, 2300.9539809203807, 2304.6197377432704, 2307.607706689867, 2311.838171327999, 2282.669012993115, 2319.014418871981, 2320.895559699809, 2325.093213646679, 2335.509542995973, 2234.731832180141, 2261.31030035839, 2274.7270327560714, 2281.327008532487, 2282.783135602115, 2186.5517568898335, 2255.23322070496, 2269.3532343282086, 2272.7272727272752, 2278.7949084582383, 1875.728019437174, 1906.8188522090518, 1990.0248878049797, 2298.7011688480557, 2302.9654555181687, 8263.173682631748, 8467.32261419085, 8483.490990540526, 8569.271611912995, 8575.742727718178, 5138.794448222066, 5289.973550132263, 5294.444083371229, 5306.389765176977, 5307.52232299095, 3462.874565766136, 3479.382087329404, 3485.093875592339, 3531.911702207465, 3564.055525285801, 1202.3627477436971, 1205.384784388923, 1235.042841797605, 1249.575144450882, 1254.8431446069242, 10729.088027517591, 10768.407737574431, 10845.783084338269, 10855.728606784814, 10896.291741036806, 4083.5099788025627, 4398.780060996937, 4454.799534020977, 4479.17135329965, 4481.35020422037, 8676.826463470821, 8684.56577171139, 8690.652373905099, 8709.956450217835, 8735.694250701195, 4616.261398176276, 4758.905451746048, 5034.647574669738, 5037.093323201859, 5079.619028572872, 4587.931181032287, 4618.35343051973, 4726.960068784931, 4769.952300476951, 4800.639952003614, 3608.386574282378, 3670.8164591770305, 3700.888973330804, 3702.7963462009848, 3754.962450375516, 977.6382738386842, 1138.1861968692424, 1138.8120960041501, 1156.1675593572613, 1156.9247998880214, 757.1746795992394, 794.9744829170305, 875.5622188905547, 920.3557509743207, 924.9352545321898, 5244.160934250578, 5484.945150548523, 5637.943620563742, 5657.179708942181, 5758.049921762946, 500.2346409992717, 534.1773668550389, 544.5317027356471, 558.1822629847293, 563.7829435667297, 3628.582712987987, 3683.650053244937, 3726.6459686329754, 3732.533433320835, 3770.7549009314307, 8928.330375221884, 9465.432074075577, 9620.470874101991, 9937.155341795884, 10024.849627255597, 9841.950790246146, 9987.950060249797, 10085.899141008496, 10107.646232381978, 10140.0, 7753.4572579919995, 7997.480163789335, 8048.959755201186, 8050.114487406264, 8069.475484093515, 5308.362996440452, 5473.987312347229, 5644.0, 5764.942350576441, 5802.622829516068, 9005.819883602422, 9285.117913798178, 9313.953430232805, 9330.346875718906, 9339.392939458914, 9016.549172541478, 9356.204722598382, 9399.154076133182, 9418.387804792666, 9440.952795236255, 7193.0, 7314.780556583311, 7321.450891183289, 7335.77992660221, 7350.448716346403, 7867.8819817703925, 7885.64514596833, 7925.326347260548, 7957.562334071682, 7967.0, 6999.545029573062, 7101.751438699621, 7199.532030418006, 7224.530405523624, 7285.8178545534665, 5963.761449541969, 7094.858102838018, 7122.18094919091, 7185.497015208885, 7186.7125314986815, 5186.273921651047, 6310.842228944313, 6395.744170233139, 6421.389967953037, 6443.420092191725, 4931.4328852181025, 4999.850004499871, 5004.274380214775, 5036.874078148076, 5106.029854327666, 4075.612816782401, 4881.7224679929295, 4903.95096049035, 4943.752812359296, 4982.878852258213], "value": 4301.421722511486, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.2434716243247, 370.41104643616643, 373.3745975491052, 377.22103855538313, 382.69002108292284, 4526.909461810762, 4791.832285869995, 5547.805826796053, 5566.471185237406, 5575.916361254584, 4963.677360971544, 4978.6514943953935, 5445.482679145484, 5446.754896029678, 5452.863678408042, 3126.0, 3635.8911831137875, 4032.3668914089694, 4095.0990782027907, 4119.40268661044, 3636.5090712753836, 4050.5418049502155, 4098.364753463218, 4140.461739973799, 4159.168166366739, 3585.2112535242204, 3750.6497660842074, 3761.6614504694708, 3975.185087057146, 4132.834686612547, 3777.131259810259, 4032.9193416131807, 4107.383892416132, 4115.876523704309, 4139.151473947833, 2760.5169095408223, 3081.9229519261976, 3278.3608195902048, 3479.6694314040124, 3484.5470088888283, 4226.36604509323, 4396.648268138541, 4396.692231543761, 5171.00916147286, 5301.257823904656, 3967.444557761916, 4297.70386383949, 4669.276262179367, 4679.9938013326955, 4687.39063921688, 5240.711760853152, 5378.623496355257, 5418.485243901823, 5674.319081710221, 5711.8000869969355, 3104.826621365031, 3207.5349074384317, 3881.146147847469, 3884.6503814656535, 3910.8267519743963, 5192.143296356135, 5665.838503106853, 5679.574031947579, 5704.458076482728, 5991.64050156992, 3556.8755093572117, 3677.338079145726, 3898.9805050974564, 3917.196974620196, 3944.7633142010995, 3967.6032396760093, 3976.8210430530694, 3990.900227494336, 3996.4604778355133, 4020.175863947942, 4005.348358734732, 4381.795006373273, 4645.210314246586, 4652.906941861212, 4693.624510039189, 5337.492938170868, 5485.314335708036, 5602.13166959122, 5804.82585522435, 5828.473773367136, 1180.1267062373954, 1275.7512285104501, 1288.6649471137384, 1296.241698606307, 1297.8702129787134, 818.9312946604738, 871.7359828249064, 879.7272845417941, 884.9247813935888, 889.7286327670123, 4368.890777730582, 4441.400410944546, 4462.188821042722, 5060.418051924076, 5115.104856650071, 4773.112267078437, 4952.662781049168, 4961.702297862068, 5670.554008727748, 5744.626599271118, 4569.544693923464, 4631.166390049756, 4815.662903596784, 5145.408278047998, 5148.56237219833, 2749.202731207927, 2880.495913215179, 2943.3524624582556, 2951.2916899943857, 2968.317287023953, 5198.194279886624, 5262.947370526323, 5296.761645725952, 5500.477454641803, 5586.748596313176, 4853.4418541867435, 5567.192757050205, 5710.914336284959, 5770.365259821417, 5898.76404943806, 4591.747453890001, 5500.559955203494, 6064.514838812796, 6121.348314606742, 6137.9079313810325, 4426.9778651106535, 4856.611471082376, 5264.499872512105, 5278.234655974786, 5965.105234214902, 4426.336049592587, 4883.804647814048, 5190.871742585522, 5224.582033437392, 5678.0915053591225, 4781.713097214039, 5352.223927530564, 5815.82552523425, 5989.790357337472, 6144.41628045335, 3206.2625596112557, 3595.1012246938267, 3904.219156168721, 3930.3121953657424, 3943.82252798619, 5937.821865344046, 5938.565188272787, 5945.256842894638, 5975.0, 6010.4891084258325, 4344.152890186383, 4418.270985287391, 5378.40837507866, 5629.268195134607, 5828.970855145697, 4737.573618374363, 5593.748281327432, 5678.403767604342, 5698.971505142614, 5705.515031222227, 5100.648328193106, 5235.816746413857, 5590.770030593263, 5592.315688469476, 5610.551155907436, 5649.954758369715, 5684.431556844448, 5709.115087161496, 5725.427457254241, 5741.368449470639, 4914.483979182278, 5544.667319960815, 5605.439456054362, 5616.612939500614, 5641.661500309994], "value": 4040.1099684483493, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [686.1491750229713, 702.546857277056, 758.3288789421364, 789.1398375770409, 796.7330944133715, 12215.20601160924, 14111.647208819772, 14968.251587420626, 15018.474353397633, 15033.62415939602, 13889.02776805624, 14960.0, 15728.449504267353, 15757.054576725404, 16140.596485087879, 900.9864852027208, 922.3082687984012, 925.5742358515057, 926.9536523173848, 930.5998420679106, 697.3863000559501, 875.2778957360172, 916.8487199612056, 929.832630126577, 932.7714709896085, 946.5030858799151, 959.1415682963743, 962.0667952086465, 962.5139304651176, 962.5668449197854, 926.8887733471959, 928.9767755806091, 954.5799848066863, 958.7603099225194, 959.5681943125586, 708.5359089796192, 715.7137145141933, 717.4009701898868, 719.0005891810388, 719.7229066809264, 12428.13003089784, 14465.421383144767, 15239.695206095927, 15265.007774494734, 15291.235438228156, 11563.190576659637, 12065.276083435023, 13646.795298070538, 13911.234882081482, 14043.0, 11096.308050037693, 11268.774624507547, 11425.714357141054, 11673.416329183507, 11675.241109327952, 3636.2551346190176, 3698.9445158322646, 3701.444783282502, 3720.274546463441, 3825.9808700956596, 80185.0, 91734.57161465457, 93938.53030734872, 94460.0, 94693.52653236758, 10483.322668373024, 10644.893551064544, 10651.0, 10835.133189344833, 10948.616798412017, 21217.363479095653, 21604.13583456644, 21626.783732162476, 22023.559528809656, 22042.0, 6393.0, 7344.816379590553, 7429.888551671729, 7510.436717246119, 7680.615969201517, 3708.8331025103935, 4075.4294398784195, 4133.710640255214, 4157.688173387008, 4199.643030342394, 3492.4586689063235, 3551.0, 3589.4615807628543, 3600.71194304455, 3610.7291953103622, 660.5145218264572, 662.4917436842334, 664.764008776887, 670.9127813384326, 676.952613317073, 643.9066335381343, 673.4645956464623, 674.9628770417675, 695.046918159972, 714.7498375568614, 9019.549022548847, 9472.715818525456, 9650.565724542412, 9769.071938165864, 9790.412575245507, 535.8151437754046, 660.5409240577849, 665.1818263535878, 668.8495088605025, 679.5073571660605, 4856.68431551948, 4918.286848406961, 5209.53114219722, 5315.335583052119, 5322.2282768998275, 86873.1312686865, 87218.0, 88423.0, 89389.0, 91366.08633913708, 67173.66413167903, 75996.0, 76293.0, 76797.0, 77181.22818771853, 67224.0, 71062.64468677728, 71426.9285960711, 71660.64169679118, 72711.63644181745, 8710.433821801562, 9368.765780855532, 9930.346667732781, 10326.483675816029, 10672.83990740124, 35561.822190889914, 43769.78115109404, 44370.0, 44925.0, 45188.87027824331, 55025.0, 56621.71689141528, 57605.423945761686, 58729.4127058724, 60242.19273421804, 12819.377341685746, 12833.935830320788, 12860.549880754503, 12882.871171288542, 13217.206967581977, 12453.630100689099, 14081.929590351983, 14166.433342666178, 14293.499727509487, 14380.928095359457, 12547.24716517012, 13179.341032948507, 13321.13412628176, 13417.463301467831, 13811.240381779098, 12846.737178207793, 13091.839162502958, 13232.933835330761, 13253.801192982306, 13491.98810089267, 11708.88291117078, 12949.54676586315, 13161.210327380388, 13359.0, 13795.034347595572, 10549.261551691616, 10630.676159757433, 10633.415162166155, 10647.94676026615, 11311.151663625096, 8069.919300807152, 8288.751337459886, 8575.485470871768, 8967.820643586962, 10493.52779124926], "value": 7255.436666770513, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1586.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 115.0, 115.0, 117.0, 119.0, 134.0, 175.0, 156.0, 117.0, 118.0], "value": 127.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 147.0, 136.0, 158.0, 114.0, 116.0, 117.0, 116.0, 183.0, 147.0], "value": 139.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [262.0, 247.0, 230.0, 200.0, 199.0, 203.0, 196.0, 198.0, 197.0, 199.0], "value": 213.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 108.0, 74.0, 86.0, 61.0, 61.0, 60.0, 71.0, 68.0, 75.0], "value": 77.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 72.0, 87.0, 72.0, 71.0, 74.0, 100.0, 61.0, 84.0, 106.0], "value": 81.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 89.0, 108.0, 135.0, 137.0, 93.0, 135.0, 137.0, 96.0], "value": 110.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 97.0, 97.0, 96.0, 96.0, 97.0, 95.0, 97.0, 98.0], "value": 96.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 95.0, 94.0, 92.0, 100.0, 123.0, 92.0, 92.0], "value": 96.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 65.0, 65.0, 63.0, 64.0, 66.0, 65.0, 63.0, 63.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 76.0, 98.0, 114.0, 109.0, 68.0, 68.0, 73.0, 73.0, 73.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 87.0, 88.0, 89.0, 91.0, 90.0, 90.0, 90.0, 93.0], "value": 89.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 149.0, 136.0, 143.0, 147.0, 145.0, 162.0, 150.0, 187.0, 146.0], "value": 152.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 179.0, 178.0, 219.0, 181.0, 162.0, 173.0, 187.0, 157.0, 236.0], "value": 185.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 86.0, 77.0, 70.0, 68.0, 67.0, 68.0, 68.0, 66.0, 70.0], "value": 70.8, "unit": "score"}]}]} 13:43:07 INFO - Return code: 0 13:43:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:43:07 ERROR - # TBPL SUCCESS # 13:43:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:43:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:43:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:43:07 INFO - 2017-03-08 13:43:07.875 screenresolution[1656:10854] starting screenresolution argv=screenresolution get 13:43:07 INFO - 2017-03-08 13:43:07.879 screenresolution[1656:10854] Display 0: 1600x1200x32@60 13:43:07 INFO - 2017-03-08 13:43:07.885 screenresolution[1657:10857] starting screenresolution argv=screenresolution list 13:43:07 INFO - Available Modes on Display 0 13:43:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:43:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:43:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:43:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:43:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:43:08 INFO - Intel Iris: 13:43:08 INFO - Chipset Model: Intel Iris 13:43:08 INFO - Type: GPU 13:43:08 INFO - Bus: Built-In 13:43:08 INFO - VRAM (Dynamic, Max): 1536 MB 13:43:08 INFO - Vendor: Intel (0x8086) 13:43:08 INFO - Device ID: 0x0a2e 13:43:08 INFO - Revision ID: 0x0009 13:43:08 INFO - Displays: 13:43:08 INFO - Display: 13:43:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:43:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:43:08 INFO - Display Serial Number: 1600x1200 60 13:43:08 INFO - Main Display: Yes 13:43:08 INFO - Mirror: Off 13:43:08 INFO - Online: Yes 13:43:08 INFO - Rotation: Supported 13:43:08 INFO - Return code: 0 13:43:08 INFO - Running post-action listener: _resource_record_post_action 13:43:08 INFO - [mozharness: 2017-03-08 21:43:08.128540Z] Finished run-tests step (success) 13:43:08 INFO - Running post-run listener: _resource_record_post_run 13:43:08 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 41323008; Write bytes: 555879936; Read time: 422; Write time: 1938 13:43:08 INFO - TinderboxPrint: CPU usage
54.5% 13:43:08 INFO - TinderboxPrint: I/O read bytes / time
41,323,008 / 422 13:43:08 INFO - TinderboxPrint: I/O write bytes / time
555,879,936 / 1,938 13:43:08 INFO - TinderboxPrint: CPU idle
2,187.1 (45.5%) 13:43:08 INFO - TinderboxPrint: CPU system
966.5 (20.1%) 13:43:08 INFO - TinderboxPrint: CPU user
1,652.1 (34.4%) 13:43:08 INFO - TinderboxPrint: Swap in / out
292,331,520 / 0 13:43:08 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 247024128; Write bytes: 241899520; Read time: 17913; Write time: 473 13:43:08 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 39143936; Write bytes: 298542592; Read time: 392; Write time: 1425 13:43:08 INFO - Running post-run listener: _upload_blobber_files 13:43:08 INFO - Blob upload gear active. 13:43:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:43:08 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 13:43:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:43:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:43:09 INFO - (blobuploader) - INFO - Open directory for files ... 13:43:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:43:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:43:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:43:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:43:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:43:11 INFO - (blobuploader) - INFO - Done attempting. 13:43:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:43:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:43:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:43:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:43:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:43:12 INFO - (blobuploader) - INFO - Done attempting. 13:43:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:43:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:43:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:43:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:43:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:43:13 INFO - (blobuploader) - INFO - Done attempting. 13:43:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:43:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:43:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:43:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:43:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:43:15 INFO - (blobuploader) - INFO - Done attempting. 13:43:15 INFO - (blobuploader) - INFO - Iteration through files over. 13:43:15 INFO - Return code: 0 13:43:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:43:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:43:15 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f46c78b172504e69d95ce2f5c4e7309115fd2271982b96fe58afe6f2c7925af59c3d11bf8facde3a8961f9a54db094cb9944b05eb48d385ec7850a63f185e17", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/23cd50c522056daafcb7354caaa0e6ecb353a7a38951b601f845f1003282521cbdd563920d5abf01bbe5a65310aec80a515df2cac58932a676e482d354540837", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a4720bf45c0be4b3f8ca264f4e482001715e19649bb4a0842cec538d748f52a0f9d81ba6a5ad54d23b890e61482b47e2c04990fef24404afb292401809017d43", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b01129173eb282b2a2944d936237e08b923d229db6726ba2594f3c633b6f46481b3140045f0f21b9637e1796c697702e60285a2c5b44b6772d0a24698b195c48"} 13:43:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:43:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:43:15 INFO - Contents: 13:43:15 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f46c78b172504e69d95ce2f5c4e7309115fd2271982b96fe58afe6f2c7925af59c3d11bf8facde3a8961f9a54db094cb9944b05eb48d385ec7850a63f185e17", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/23cd50c522056daafcb7354caaa0e6ecb353a7a38951b601f845f1003282521cbdd563920d5abf01bbe5a65310aec80a515df2cac58932a676e482d354540837", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a4720bf45c0be4b3f8ca264f4e482001715e19649bb4a0842cec538d748f52a0f9d81ba6a5ad54d23b890e61482b47e2c04990fef24404afb292401809017d43", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b01129173eb282b2a2944d936237e08b923d229db6726ba2594f3c633b6f46481b3140045f0f21b9637e1796c697702e60285a2c5b44b6772d0a24698b195c48"} 13:43:15 INFO - Running post-run listener: copy_logs_to_upload_dir 13:43:15 INFO - Copying logs to upload dir... 13:43:15 INFO - mkdir: /builds/slave/test/build/upload/logs 13:43:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.484989 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-08 13:43:15.742103) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-08 13:43:15.745279) ========= 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.Pf9qpTb84F/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.YQcYEGwgFr/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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/6f46c78b172504e69d95ce2f5c4e7309115fd2271982b96fe58afe6f2c7925af59c3d11bf8facde3a8961f9a54db094cb9944b05eb48d385ec7850a63f185e17", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/23cd50c522056daafcb7354caaa0e6ecb353a7a38951b601f845f1003282521cbdd563920d5abf01bbe5a65310aec80a515df2cac58932a676e482d354540837", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a4720bf45c0be4b3f8ca264f4e482001715e19649bb4a0842cec538d748f52a0f9d81ba6a5ad54d23b890e61482b47e2c04990fef24404afb292401809017d43", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b01129173eb282b2a2944d936237e08b923d229db6726ba2594f3c633b6f46481b3140045f0f21b9637e1796c697702e60285a2c5b44b6772d0a24698b195c48"} 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.011272 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/6f46c78b172504e69d95ce2f5c4e7309115fd2271982b96fe58afe6f2c7925af59c3d11bf8facde3a8961f9a54db094cb9944b05eb48d385ec7850a63f185e17", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/23cd50c522056daafcb7354caaa0e6ecb353a7a38951b601f845f1003282521cbdd563920d5abf01bbe5a65310aec80a515df2cac58932a676e482d354540837", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a4720bf45c0be4b3f8ca264f4e482001715e19649bb4a0842cec538d748f52a0f9d81ba6a5ad54d23b890e61482b47e2c04990fef24404afb292401809017d43", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b01129173eb282b2a2944d936237e08b923d229db6726ba2594f3c633b6f46481b3140045f0f21b9637e1796c697702e60285a2c5b44b6772d0a24698b195c48"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-08 13:43:15.780033) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 13:43:15.780430) ========= 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.Pf9qpTb84F/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.YQcYEGwgFr/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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.005121 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 13:43:15.829865) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-08 13:43:15.830182) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-08 13:43:15.830506) ========= ========= Total master_lag: 0.18 =========