builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0256 starttime: 1468444827.84 results: success (0) buildid: 20160713120145 builduid: 4136cd9c10af4aeb8477e41b53b2c57b revision: 9ce9896423df2ce58e0ec22486d6519d8221a362 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 14:20:27.844171) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 14:20:27.844595) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 14:20:27.844886) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 14:20:27.869733) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 14:20:27.870122) ========= 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.AC1ZWLbL6W/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.XbE1jDHOCp/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 --2016-07-13 14:20:27-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.7M=0.001s 2016-07-13 14:20:27 (18.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.122977 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 14:20:28.015318) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 14:20:28.015630) ========= 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.AC1ZWLbL6W/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.XbE1jDHOCp/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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.026084 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 14:20:28.064518) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-13 14:20:28.064879) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9ce9896423df2ce58e0ec22486d6519d8221a362 --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 9ce9896423df2ce58e0ec22486d6519d8221a362 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AC1ZWLbL6W/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.XbE1jDHOCp/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 2016-07-13 14:20:28,145 truncating revision to first 12 chars 2016-07-13 14:20:28,145 Setting DEBUG logging. 2016-07-13 14:20:28,145 attempt 1/10 2016-07-13 14:20:28,145 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9ce9896423df?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-13 14:20:29,432 unpacking tar archive at: mozilla-central-9ce9896423df/testing/mozharness/ program finished with exit code 0 elapsedTime=1.543501 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-13 14:20:29.630085) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 14:20:29.630430) ========= script_repo_revision: 9ce9896423df2ce58e0ec22486d6519d8221a362 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 14:20:29.630846) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 14:20:29.631118) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 14:20:29.649318) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-07-13 14:20:29.649727) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--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.AC1ZWLbL6W/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.XbE1jDHOCp/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 14:20:29 INFO - MultiFileLogger online at 20160713 14:20:29 in /builds/slave/test 14:20:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 14:20:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:20:29 INFO - {'append_to_log': False, 14:20:29 INFO - 'base_work_dir': '/builds/slave/test', 14:20:29 INFO - 'blob_upload_branch': 'Firefox', 14:20:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:20:29 INFO - 'branch': 'Firefox', 14:20:29 INFO - 'buildbot_json_path': 'buildprops.json', 14:20:29 INFO - 'config_files': ('talos/mac_config.py',), 14:20:29 INFO - 'default_actions': ('clobber', 14:20:29 INFO - 'read-buildbot-config', 14:20:29 INFO - 'download-and-extract', 14:20:29 INFO - 'populate-webroot', 14:20:29 INFO - 'create-virtualenv', 14:20:29 INFO - 'install', 14:20:29 INFO - 'run-tests'), 14:20:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:20:29 INFO - 'download_minidump_stackwalk': True, 14:20:29 INFO - 'download_symbols': 'ondemand', 14:20:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:20:29 INFO - 'tooltool.py': '/tools/tooltool.py', 14:20:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:20:29 INFO - '/tools/misc-python/virtualenv.py')}, 14:20:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:20:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:20:29 INFO - 'installer_path': 'installer.exe', 14:20:29 INFO - 'log_level': 'info', 14:20:29 INFO - 'log_name': 'talos', 14:20:29 INFO - 'log_to_console': True, 14:20:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:20:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:20:29 INFO - 'opt_config_files': (), 14:20:29 INFO - 'pip_index': False, 14:20:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:20:29 INFO - 'cmd': ('bash', 14:20:29 INFO - '-c', 14:20:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:20:29 INFO - 'enabled': True, 14:20:29 INFO - 'halt_on_failure': False, 14:20:29 INFO - 'name': 'check_screen_resolution'},), 14:20:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:20:29 INFO - 'cmd': ('bash', 14:20:29 INFO - '-c', 14:20:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:20:29 INFO - 'enabled': True, 14:20:29 INFO - 'halt_on_failure': False, 14:20:29 INFO - 'name': 'check_screen_resolution'},), 14:20:29 INFO - 'run_cmd_checks_enabled': True, 14:20:29 INFO - 'sps_profile': False, 14:20:29 INFO - 'sps_profile_interval': 0, 14:20:29 INFO - 'suite': 'dromaeojs', 14:20:29 INFO - 'system_bits': '32', 14:20:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:20:29 INFO - 'title': 't-yosemite-r7-0256', 14:20:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:20:29 INFO - 'use_talos_json': True, 14:20:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:20:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:20:29 INFO - 'work_dir': 'build'} 14:20:29 INFO - ##### 14:20:29 INFO - ##### Running clobber step. 14:20:29 INFO - ##### 14:20:29 INFO - Running pre-action listener: _resource_record_pre_action 14:20:29 INFO - Running main action method: clobber 14:20:29 INFO - rmtree: /builds/slave/test/build 14:20:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:20:31 INFO - Running post-action listener: _resource_record_post_action 14:20:31 INFO - ##### 14:20:31 INFO - ##### Finished clobber step (success) 14:20:31 INFO - ##### 14:20:31 INFO - ##### 14:20:31 INFO - ##### Running read-buildbot-config step. 14:20:31 INFO - ##### 14:20:31 INFO - Running pre-action listener: _resource_record_pre_action 14:20:31 INFO - Running main action method: read_buildbot_config 14:20:31 INFO - Using buildbot properties: 14:20:31 INFO - { 14:20:31 INFO - "project": "", 14:20:31 INFO - "product": "firefox", 14:20:31 INFO - "script_repo_revision": "production", 14:20:31 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 14:20:31 INFO - "repository": "", 14:20:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 14:20:31 INFO - "buildid": "20160713120145", 14:20:31 INFO - "pgo_build": "False", 14:20:31 INFO - "basedir": "/builds/slave/test", 14:20:31 INFO - "buildnumber": 46, 14:20:31 INFO - "slavename": "t-yosemite-r7-0256", 14:20:31 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 14:20:31 INFO - "platform": "yosemite_r7", 14:20:31 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 14:20:31 INFO - "branch": "mozilla-central", 14:20:31 INFO - "repo_path": "mozilla-central", 14:20:31 INFO - "revision": "9ce9896423df2ce58e0ec22486d6519d8221a362", 14:20:31 INFO - "stage_platform": "macosx64", 14:20:31 INFO - "builduid": "4136cd9c10af4aeb8477e41b53b2c57b", 14:20:31 INFO - "slavebuilddir": "test" 14:20:31 INFO - } 14:20:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 14:20:31 INFO - Running post-action listener: _resource_record_post_action 14:20:31 INFO - ##### 14:20:31 INFO - ##### Finished read-buildbot-config step (success) 14:20:31 INFO - ##### 14:20:31 INFO - ##### 14:20:31 INFO - ##### Running download-and-extract step. 14:20:31 INFO - ##### 14:20:31 INFO - Running pre-action listener: _resource_record_pre_action 14:20:31 INFO - Running main action method: download_and_extract 14:20:31 INFO - mkdir: /builds/slave/test/build/tests 14:20:31 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:20:31 INFO - https://queue.taskcluster.net/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 14:20:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 14:20:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 14:20:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 14:20:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 14:20:33 INFO - Downloaded 1199 bytes. 14:20:33 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 14:20:33 INFO - Using the following test package requirements: 14:20:33 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 14:20:33 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 14:20:33 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 14:20:33 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 14:20:33 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 14:20:33 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 14:20:33 INFO - u'jsshell-mac.zip'], 14:20:33 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 14:20:33 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 14:20:33 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 14:20:33 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 14:20:33 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 14:20:33 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 14:20:33 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 14:20:33 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 14:20:33 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 14:20:33 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 14:20:33 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 14:20:33 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:20:33 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:20:33 INFO - https://queue.taskcluster.net/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:20:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 14:20:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 14:20:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 14:20:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:20:34 INFO - Downloaded 18827263 bytes. 14:20:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:20:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:20:37 INFO - Return code: 0 14:20:37 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:20:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:20:37 INFO - https://queue.taskcluster.net/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:20:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 14:20:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 14:20:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 14:20:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:20:38 INFO - Downloaded 18827263 bytes. 14:20:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:20:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:20:41 INFO - Return code: 0 14:20: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:20:41 INFO - https://queue.taskcluster.net/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 14:20:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 14:20:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 14:20:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 14:20:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:20:47 INFO - Downloaded 14403317 bytes. 14:20:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:20:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 14:20:48 INFO - Return code: 0 14:20:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:20:48 INFO - https://queue.taskcluster.net/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 14:20:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 14:20:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 14:20:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 14:20:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 14:20:51 INFO - Downloaded 112779423 bytes. 14:20:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 14:20:51 INFO - mkdir: /builds/slave/test/properties 14:20:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:20:51 INFO - Writing to file /builds/slave/test/properties/build_url 14:20:51 INFO - Contents: 14:20:51 INFO - build_url:https://queue.taskcluster.net/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 14:20:52 INFO - Running post-action listener: _resource_record_post_action 14:20:52 INFO - Running post-action listener: set_extra_try_arguments 14:20:52 INFO - ##### 14:20:52 INFO - ##### Finished download-and-extract step (success) 14:20:52 INFO - ##### 14:20:52 INFO - ##### 14:20:52 INFO - ##### Running populate-webroot step. 14:20:52 INFO - ##### 14:20:52 INFO - Running pre-action listener: _resource_record_pre_action 14:20:52 INFO - Running main action method: populate_webroot 14:20:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:20:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:20:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:20:52 INFO - '--tppagecycles', 14:20:52 INFO - '7'], 14:20:52 INFO - 'tests': ['tsvgm']}}, 14:20:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:20:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:20:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:20:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:20:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:20:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:20:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:20:52 INFO - 'talos_options': ['--disable-e10s'], 14:20:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:20:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:20:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:20:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:20:52 INFO - 'talos_options': ['--disable-e10s'], 14:20:52 INFO - 'tests': ['damp', 'tps']}, 14:20:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:20:52 INFO - 'tests': ['damp', 'tps']}, 14:20:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:20:52 INFO - 'tests': ['dromaeo_dom']}, 14:20:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:20:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:20:52 INFO - 'tests': ['basic_compositor_video']}, 14:20:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:20:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:20:52 INFO - 'tests': ['a11yr', 14:20:52 INFO - 'ts_paint', 14:20:52 INFO - 'tpaint', 14:20:52 INFO - 'sessionrestore', 14:20:52 INFO - 'sessionrestore_no_auto_restore', 14:20:52 INFO - 'tabpaint']}, 14:20:52 INFO - 'other-e10s': {'tests': ['a11yr', 14:20:52 INFO - 'ts_paint', 14:20:52 INFO - 'tpaint', 14:20:52 INFO - 'sessionrestore', 14:20:52 INFO - 'sessionrestore_no_auto_restore', 14:20:52 INFO - 'tabpaint']}, 14:20:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:20:52 INFO - 'ts_paint', 14:20:52 INFO - 'tpaint', 14:20:52 INFO - 'sessionrestore', 14:20:52 INFO - 'sessionrestore_no_auto_restore', 14:20:52 INFO - 'tabpaint']}, 14:20:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:20:52 INFO - 'ts_paint', 14:20:52 INFO - 'tpaint', 14:20:52 INFO - 'sessionrestore', 14:20:52 INFO - 'sessionrestore_no_auto_restore', 14:20:52 INFO - 'tabpaint']}, 14:20:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:20:52 INFO - 'tests': ['a11yr', 14:20:52 INFO - 'ts_paint', 14:20:52 INFO - 'tpaint', 14:20:52 INFO - 'sessionrestore', 14:20:52 INFO - 'sessionrestore_no_auto_restore', 14:20:52 INFO - 'tabpaint']}, 14:20:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:20:52 INFO - 'tests': ['a11yr', 14:20:52 INFO - 'ts_paint', 14:20:52 INFO - 'tpaint', 14:20:52 INFO - 'sessionrestore', 14:20:52 INFO - 'sessionrestore_no_auto_restore', 14:20:52 INFO - 'tabpaint']}, 14:20:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:20:52 INFO - 'tests': ['tsvgx', 14:20:52 INFO - 'tsvgr_opacity', 14:20:52 INFO - 'tart', 14:20:52 INFO - 'tscrollx', 14:20:52 INFO - 'cart']}, 14:20:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:20:52 INFO - 'tsvgr_opacity', 14:20:52 INFO - 'tart', 14:20:52 INFO - 'tscrollx', 14:20:52 INFO - 'cart']}, 14:20:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:20:52 INFO - 'talos_options': ['--disable-e10s'], 14:20:52 INFO - 'tests': ['tp5o']}, 14:20:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:20:52 INFO - 'tests': ['tp5o']}, 14:20:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:20:52 INFO - 'talos_options': ['--disable-e10s', 14:20:52 INFO - '--xperf_path', 14:20:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:20:52 INFO - 'tests': ['tp5n']}, 14:20:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:20:52 INFO - 'talos_options': ['--xperf_path', 14:20:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:20:52 INFO - 'tests': ['tp5n']}}, 14:20:52 INFO - 'talos.zip': {'path': '', 14:20:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:20:52 INFO - Running post-action listener: _resource_record_post_action 14:20:52 INFO - ##### 14:20:52 INFO - ##### Finished populate-webroot step (success) 14:20:52 INFO - ##### 14:20:52 INFO - ##### 14:20:52 INFO - ##### Running create-virtualenv step. 14:20:52 INFO - ##### 14:20:52 INFO - Running pre-action listener: _resource_record_pre_action 14:20:52 INFO - Running main action method: create_virtualenv 14:20:52 INFO - Creating virtualenv /builds/slave/test/build/venv 14:20:52 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 14:20:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:20:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:20:52 INFO - Using real prefix '/tools/python27' 14:20:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:20:53 INFO - Installing distribute.............................................................................................................................................................................................done. 14:20:56 INFO - Installing pip.................done. 14:20:56 INFO - Return code: 0 14:20:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:20:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:20:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:20:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:20:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:20:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10142aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014bf618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdbbcb2350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10138ed30>, '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 0x1010f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101386b20>, '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.AC1ZWLbL6W/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.XbE1jDHOCp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 14:20:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:20:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:20:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AC1ZWLbL6W/Render', 14:20:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:20:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:20:56 INFO - 'HOME': '/Users/cltbld', 14:20:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:20:56 INFO - 'LOGNAME': 'cltbld', 14:20:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:20:56 INFO - 'MOZ_NO_REMOTE': '1', 14:20:56 INFO - 'NO_EM_RESTART': '1', 14:20:56 INFO - 'PAGER': '/bin/cat', 14:20:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:20:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:20:56 INFO - 'PWD': '/builds/slave/test', 14:20:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:20:56 INFO - 'SHELL': '/bin/bash', 14:20:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XbE1jDHOCp/Listeners', 14:20:56 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 14:20:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:20:56 INFO - 'USER': 'cltbld', 14:20:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:20:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:20:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:20:56 INFO - 'XPC_FLAGS': '0x0', 14:20:56 INFO - 'XPC_SERVICE_NAME': '0', 14:20:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:20:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:20:56 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 14:20:56 INFO - Cleaning up... 14:20:56 INFO - Return code: 0 14:20:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:20:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:20:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:20:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:20:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:20:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10142aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014bf618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdbbcb2350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10138ed30>, '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 0x1010f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101386b20>, '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.AC1ZWLbL6W/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.XbE1jDHOCp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 14:20:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:20:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:20:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AC1ZWLbL6W/Render', 14:20:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:20:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:20:56 INFO - 'HOME': '/Users/cltbld', 14:20:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:20:56 INFO - 'LOGNAME': 'cltbld', 14:20:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:20:56 INFO - 'MOZ_NO_REMOTE': '1', 14:20:56 INFO - 'NO_EM_RESTART': '1', 14:20:56 INFO - 'PAGER': '/bin/cat', 14:20:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:20:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:20:56 INFO - 'PWD': '/builds/slave/test', 14:20:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:20:56 INFO - 'SHELL': '/bin/bash', 14:20:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XbE1jDHOCp/Listeners', 14:20:56 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 14:20:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:20:56 INFO - 'USER': 'cltbld', 14:20:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:20:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:20:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:20:56 INFO - 'XPC_FLAGS': '0x0', 14:20:56 INFO - 'XPC_SERVICE_NAME': '0', 14:20:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:20:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:20:56 INFO - Downloading/unpacking psutil>=3.1.1 14:20:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:20:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:20:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:20:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:20:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:20:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:20:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:20:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:20:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:20:59 INFO - Installing collected packages: psutil 14:20:59 INFO - Running setup.py install for psutil 14:20:59 INFO - building 'psutil._psutil_osx' extension 14:20:59 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 14:20:59 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 14:20:59 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 14:20:59 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 14:21:00 INFO - building 'psutil._psutil_posix' extension 14:21:00 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 14:21:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:21:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:21:00 INFO - ^ 14:21:00 INFO - 1 warning generated. 14:21:00 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 14:21:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:21:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:21:00 INFO - Successfully installed psutil 14:21:00 INFO - Cleaning up... 14:21:00 INFO - Return code: 0 14:21:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:21:00 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:21:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:21:00 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:21:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:21:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:21:00 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 0x10142aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014bf618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdbbcb2350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10138ed30>, '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 0x1010f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101386b20>, '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.AC1ZWLbL6W/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.XbE1jDHOCp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 14:21:00 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 14:21:00 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 14:21:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AC1ZWLbL6W/Render', 14:21:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:21:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:21:00 INFO - 'HOME': '/Users/cltbld', 14:21:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:21:00 INFO - 'LOGNAME': 'cltbld', 14:21:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:21:00 INFO - 'MOZ_NO_REMOTE': '1', 14:21:00 INFO - 'NO_EM_RESTART': '1', 14:21:00 INFO - 'PAGER': '/bin/cat', 14:21:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:21:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:21:00 INFO - 'PWD': '/builds/slave/test', 14:21:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:21:00 INFO - 'SHELL': '/bin/bash', 14:21:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XbE1jDHOCp/Listeners', 14:21:00 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 14:21:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:21:00 INFO - 'USER': 'cltbld', 14:21:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:21:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:21:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:21:00 INFO - 'XPC_FLAGS': '0x0', 14:21:00 INFO - 'XPC_SERVICE_NAME': '0', 14:21:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:21:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:21:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:21:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:21:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:21:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:21:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:21:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:21:03 INFO - Installing collected packages: mozsystemmonitor 14:21:03 INFO - Running setup.py install for mozsystemmonitor 14:21:03 INFO - Successfully installed mozsystemmonitor 14:21:03 INFO - Cleaning up... 14:21:03 INFO - Return code: 0 14:21:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:21:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:21:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:21:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:21:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:21:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:21:03 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 0x10142aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014bf618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdbbcb2350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10138ed30>, '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 0x1010f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101386b20>, '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.AC1ZWLbL6W/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.XbE1jDHOCp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 14:21:03 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 14:21:03 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 14:21:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AC1ZWLbL6W/Render', 14:21:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:21:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:21:03 INFO - 'HOME': '/Users/cltbld', 14:21:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:21:03 INFO - 'LOGNAME': 'cltbld', 14:21:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:21:03 INFO - 'MOZ_NO_REMOTE': '1', 14:21:03 INFO - 'NO_EM_RESTART': '1', 14:21:03 INFO - 'PAGER': '/bin/cat', 14:21:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:21:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:21:03 INFO - 'PWD': '/builds/slave/test', 14:21:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:21:03 INFO - 'SHELL': '/bin/bash', 14:21:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XbE1jDHOCp/Listeners', 14:21:03 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 14:21:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:21:03 INFO - 'USER': 'cltbld', 14:21:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:21:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:21:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:21:03 INFO - 'XPC_FLAGS': '0x0', 14:21:03 INFO - 'XPC_SERVICE_NAME': '0', 14:21:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:21:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:21:03 INFO - Downloading/unpacking jsonschema==2.5.1 14:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:21:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:21:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:21:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:21:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:21:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:21:06 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 14:21:06 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 14:21:06 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 14:21:06 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 14:21:06 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 14:21:06 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 14:21:06 INFO - Downloading functools32-3.2.3-2.tar.gz 14:21:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:21:06 INFO - warning: no files found matching '*.txt' 14:21:06 INFO - no previously-included directories found matching 'build' 14:21:06 INFO - no previously-included directories found matching 'dist' 14:21:06 INFO - no previously-included directories found matching '.git*' 14:21:06 INFO - Installing collected packages: jsonschema, functools32 14:21:06 INFO - Running setup.py install for functools32 14:21:06 INFO - warning: no files found matching '*.txt' 14:21:06 INFO - no previously-included directories found matching 'build' 14:21:06 INFO - no previously-included directories found matching 'dist' 14:21:06 INFO - no previously-included directories found matching '.git*' 14:21:06 INFO - Successfully installed jsonschema functools32 14:21:06 INFO - Cleaning up... 14:21:07 INFO - Return code: 0 14:21:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:21:07 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:21:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:21:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:21:07 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:21:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:21:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:21:07 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 0x10142aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014bf618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdbbcb2350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10138ed30>, '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 0x1010f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101386b20>, '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.AC1ZWLbL6W/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.XbE1jDHOCp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 14:21:07 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 14:21:07 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 14:21:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AC1ZWLbL6W/Render', 14:21:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:21:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:21:07 INFO - 'HOME': '/Users/cltbld', 14:21:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:21:07 INFO - 'LOGNAME': 'cltbld', 14:21:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:21:07 INFO - 'MOZ_NO_REMOTE': '1', 14:21:07 INFO - 'NO_EM_RESTART': '1', 14:21:07 INFO - 'PAGER': '/bin/cat', 14:21:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:21:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:21:07 INFO - 'PWD': '/builds/slave/test', 14:21:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:21:07 INFO - 'SHELL': '/bin/bash', 14:21:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XbE1jDHOCp/Listeners', 14:21:07 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 14:21:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:21:07 INFO - 'USER': 'cltbld', 14:21:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:21:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:21:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:21:07 INFO - 'XPC_FLAGS': '0x0', 14:21:07 INFO - 'XPC_SERVICE_NAME': '0', 14:21:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:21:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:21:07 INFO - Downloading/unpacking blobuploader==1.2.4 14:21:07 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 14:21:07 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 14:21:07 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 14:21:07 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 14:21:07 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 14:21:07 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 14:21:09 INFO - Downloading blobuploader-1.2.4.tar.gz 14:21:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:21:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:21:09 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 14:21:09 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 14:21:09 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 14:21:09 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 14:21:09 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 14:21:09 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 14:21:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:21:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:21:10 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 14:21:10 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 14:21:10 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 14:21:10 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 14:21:10 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 14:21:10 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 14:21:10 INFO - Downloading docopt-0.6.1.tar.gz 14:21:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:21:11 INFO - Installing collected packages: blobuploader, requests, docopt 14:21:11 INFO - Running setup.py install for blobuploader 14:21:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:21:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:21:11 INFO - Running setup.py install for requests 14:21:11 INFO - Running setup.py install for docopt 14:21:11 INFO - Successfully installed blobuploader requests docopt 14:21:11 INFO - Cleaning up... 14:21:11 INFO - Return code: 0 14:21:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:21:11 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:21:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:21:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:21:11 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:21:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:21:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:21:11 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 0x10142aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014bf618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdbbcb2350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10138ed30>, '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 0x1010f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101386b20>, '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.AC1ZWLbL6W/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.XbE1jDHOCp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 14:21:11 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 14:21:11 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 14:21:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AC1ZWLbL6W/Render', 14:21:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:21:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:21:11 INFO - 'HOME': '/Users/cltbld', 14:21:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:21:11 INFO - 'LOGNAME': 'cltbld', 14:21:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:21:11 INFO - 'MOZ_NO_REMOTE': '1', 14:21:11 INFO - 'NO_EM_RESTART': '1', 14:21:11 INFO - 'PAGER': '/bin/cat', 14:21:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:21:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:21:11 INFO - 'PWD': '/builds/slave/test', 14:21:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:21:11 INFO - 'SHELL': '/bin/bash', 14:21:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XbE1jDHOCp/Listeners', 14:21:11 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 14:21:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:21:11 INFO - 'USER': 'cltbld', 14:21:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:21:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:21:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:21:11 INFO - 'XPC_FLAGS': '0x0', 14:21:11 INFO - 'XPC_SERVICE_NAME': '0', 14:21:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:21:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:21:12 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-kzlUkQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:21:12 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-axUQxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:21:12 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-3WG4Sc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:21:12 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-jmUIZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:21:12 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-yA95XV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:21:12 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-kwzO_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:21:12 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-QLoehB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:21:12 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-EGr48o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:21:12 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-jhCNph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:21:13 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-DYdGI3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:21:13 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-lzSxzR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:21:13 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-RiIU3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:21:13 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-4gohe3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:21:13 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-vDmpYO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:21:13 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-qSyiuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:21:13 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-tsKK5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:21:13 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-7rLMEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:21:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:21:13 INFO - Running setup.py install for manifestparser 14:21:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:21:14 INFO - Running setup.py install for mozcrash 14:21:14 INFO - Running setup.py install for mozdebug 14:21:14 INFO - Running setup.py install for mozdevice 14:21:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:21:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:21:14 INFO - Running setup.py install for mozfile 14:21:14 INFO - Running setup.py install for mozhttpd 14:21:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:21:14 INFO - Running setup.py install for mozinfo 14:21:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:21:14 INFO - Running setup.py install for mozInstall 14:21:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:21:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:21:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:21:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:21:15 INFO - Running setup.py install for mozleak 14:21:15 INFO - Running setup.py install for mozlog 14:21:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:21:15 INFO - Running setup.py install for moznetwork 14:21:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:21:15 INFO - Running setup.py install for mozprocess 14:21:15 INFO - Running setup.py install for mozprofile 14:21:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:21:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:21:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:21:15 INFO - Running setup.py install for mozrunner 14:21:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:21:16 INFO - Running setup.py install for mozscreenshot 14:21:16 INFO - Running setup.py install for moztest 14:21:16 INFO - Running setup.py install for mozversion 14:21:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:21:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:21:16 INFO - Cleaning up... 14:21:16 INFO - Return code: 0 14:21:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:21:16 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:21:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:21:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:21:16 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:21:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:21:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:21:16 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 0x10142aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014bf618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdbbcb2350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10138ed30>, '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 0x1010f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101386b20>, '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.AC1ZWLbL6W/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.XbE1jDHOCp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 14:21:16 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 14:21:16 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 14:21:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AC1ZWLbL6W/Render', 14:21:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:21:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:21:16 INFO - 'HOME': '/Users/cltbld', 14:21:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:21:16 INFO - 'LOGNAME': 'cltbld', 14:21:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:21:16 INFO - 'MOZ_NO_REMOTE': '1', 14:21:16 INFO - 'NO_EM_RESTART': '1', 14:21:16 INFO - 'PAGER': '/bin/cat', 14:21:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:21:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:21:16 INFO - 'PWD': '/builds/slave/test', 14:21:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:21:16 INFO - 'SHELL': '/bin/bash', 14:21:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XbE1jDHOCp/Listeners', 14:21:16 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 14:21:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:21:16 INFO - 'USER': 'cltbld', 14:21:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:21:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:21:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:21:16 INFO - 'XPC_FLAGS': '0x0', 14:21:16 INFO - 'XPC_SERVICE_NAME': '0', 14:21:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:21:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:21:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:21:16 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-wzXHw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:21:16 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)) 14:21:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:21:16 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-vnsQlF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:21:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 14:21:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:21:16 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-SQOw6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:21:17 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)) 14:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:21:17 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-eL97le-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:21:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 14:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:21:17 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-mk68d3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:21:17 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)) 14:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:21:17 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-2EKpvq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:21:17 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)) 14:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:21:17 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-OZ0Mdy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:21:17 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)) 14:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:21:17 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-o1cXSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:21:17 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)) 14:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:21:17 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-QThOyG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:21:17 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)) 14:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:21:17 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-wLMog2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:21:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 14:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:21:17 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-E5XSoz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:21:17 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)) 14:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:21:17 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-UhXpYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:21:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 14:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:21:18 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-fiWX1O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:21:18 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)) 14:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:21:18 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-yzDTiJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:21:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 14:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:21:18 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-WEfW8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:21:18 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)) 14:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:21:18 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-C_67_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:21:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 14:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:21:18 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-uYg81V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:21:18 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)) 14:21:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:21:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:21:18 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)) 14:21:18 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:21:18 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:21:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:21:18 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 14:21:18 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 14:21:18 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 14:21:18 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 14:21:18 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 14:21:18 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 14:21:20 INFO - Downloading blessings-1.6.tar.gz 14:21:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:21:21 INFO - Installing collected packages: blessings 14:21:21 INFO - Running setup.py install for blessings 14:21:21 INFO - Successfully installed blessings 14:21:21 INFO - Cleaning up... 14:21:21 INFO - Return code: 0 14:21:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:21:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:21:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:21:21 INFO - Reading from file tmpfile_stdout 14:21:21 INFO - Current package versions: 14:21:21 INFO - blessings == 1.6 14:21:21 INFO - blobuploader == 1.2.4 14:21:21 INFO - docopt == 0.6.1 14:21:21 INFO - functools32 == 3.2.3-2 14:21:21 INFO - jsonschema == 2.5.1 14:21:21 INFO - manifestparser == 1.1 14:21:21 INFO - mozInstall == 1.12 14:21:21 INFO - mozcrash == 0.17 14:21:21 INFO - mozdebug == 0.1 14:21:21 INFO - mozdevice == 0.48 14:21:21 INFO - mozfile == 1.2 14:21:21 INFO - mozhttpd == 0.7 14:21:21 INFO - mozinfo == 0.9 14:21:21 INFO - mozleak == 0.1 14:21:21 INFO - mozlog == 3.2 14:21:21 INFO - moznetwork == 0.27 14:21:21 INFO - mozprocess == 0.23 14:21:21 INFO - mozprofile == 0.28 14:21:21 INFO - mozrunner == 6.12 14:21:21 INFO - mozscreenshot == 0.1 14:21:21 INFO - mozsystemmonitor == 0.3 14:21:21 INFO - moztest == 0.7 14:21:21 INFO - mozversion == 1.4 14:21:21 INFO - psutil == 3.1.1 14:21:21 INFO - requests == 1.2.3 14:21:21 INFO - wsgiref == 0.1.2 14:21:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:21:21 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:21:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:21:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:21:21 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:21:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:21:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:21:21 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 0x10142aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014bf618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdbbcb2350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10138ed30>, '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 0x1010f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101386b20>, '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.AC1ZWLbL6W/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.XbE1jDHOCp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 14:21:21 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 14:21:21 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 14:21:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AC1ZWLbL6W/Render', 14:21:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:21:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:21:21 INFO - 'HOME': '/Users/cltbld', 14:21:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:21:21 INFO - 'LOGNAME': 'cltbld', 14:21:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:21:21 INFO - 'MOZ_NO_REMOTE': '1', 14:21:21 INFO - 'NO_EM_RESTART': '1', 14:21:21 INFO - 'PAGER': '/bin/cat', 14:21:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:21:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:21:21 INFO - 'PWD': '/builds/slave/test', 14:21:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:21:21 INFO - 'SHELL': '/bin/bash', 14:21:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XbE1jDHOCp/Listeners', 14:21:21 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 14:21:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:21:21 INFO - 'USER': 'cltbld', 14:21:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:21:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:21:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:21:21 INFO - 'XPC_FLAGS': '0x0', 14:21:21 INFO - 'XPC_SERVICE_NAME': '0', 14:21:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:21:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:21:21 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)) 14:21:21 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)) 14:21:21 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)) 14:21:21 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)) 14:21:21 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)) 14:21:21 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)) 14:21:21 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)) 14:21:21 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)) 14:21:21 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)) 14:21:21 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)) 14:21:21 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)) 14:21:21 INFO - Cleaning up... 14:21:21 INFO - Return code: 0 14:21:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:21:21 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:21:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:21:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:21:21 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:21:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:21:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:21:21 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 0x10142aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014bf618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdbbcb2350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10138ed30>, '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 0x1010f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101386b20>, '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.AC1ZWLbL6W/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.XbE1jDHOCp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 14:21:21 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 14:21:21 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 14:21:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AC1ZWLbL6W/Render', 14:21:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:21:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:21:21 INFO - 'HOME': '/Users/cltbld', 14:21:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:21:21 INFO - 'LOGNAME': 'cltbld', 14:21:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:21:21 INFO - 'MOZ_NO_REMOTE': '1', 14:21:21 INFO - 'NO_EM_RESTART': '1', 14:21:21 INFO - 'PAGER': '/bin/cat', 14:21:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:21:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:21:21 INFO - 'PWD': '/builds/slave/test', 14:21:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:21:21 INFO - 'SHELL': '/bin/bash', 14:21:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XbE1jDHOCp/Listeners', 14:21:21 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 14:21:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:21:21 INFO - 'USER': 'cltbld', 14:21:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:21:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:21:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:21:21 INFO - 'XPC_FLAGS': '0x0', 14:21:21 INFO - 'XPC_SERVICE_NAME': '0', 14:21:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:21:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:21:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:21:22 INFO - Cleaning up... 14:21:22 INFO - Return code: 0 14:21:22 INFO - Running post-action listener: _resource_record_post_action 14:21:22 INFO - Running post-action listener: _start_resource_monitoring 14:21:22 INFO - Starting resource monitoring. 14:21:22 INFO - ##### 14:21:22 INFO - ##### Finished create-virtualenv step (success) 14:21:22 INFO - ##### 14:21:22 INFO - ##### 14:21:22 INFO - ##### Running install step. 14:21:22 INFO - ##### 14:21:22 INFO - Running pre-action listener: _resource_record_pre_action 14:21:22 INFO - Running main action method: install 14:21:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:21:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:21:22 INFO - Reading from file tmpfile_stdout 14:21:22 INFO - Detecting whether we're running mozinstall >=1.0... 14:21:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:21:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:21:22 INFO - Reading from file tmpfile_stdout 14:21:22 INFO - Output received: 14:21:22 INFO - Usage: mozinstall [options] installer 14:21:22 INFO - Options: 14:21:22 INFO - -h, --help show this help message and exit 14:21:22 INFO - -d DEST, --destination=DEST 14:21:22 INFO - Directory to install application into. [default: 14:21:22 INFO - "/builds/slave/test"] 14:21:22 INFO - --app=APP Application being installed. [default: firefox] 14:21:22 INFO - mkdir: /builds/slave/test/build/application 14:21:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:21:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:21:56 INFO - Reading from file tmpfile_stdout 14:21:56 INFO - Output received: 14:21:56 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:21:56 INFO - Running post-action listener: _resource_record_post_action 14:21:56 INFO - ##### 14:21:56 INFO - ##### Finished install step (success) 14:21:56 INFO - ##### 14:21:56 INFO - ##### 14:21:56 INFO - ##### Running run-tests step. 14:21:56 INFO - ##### 14:21:56 INFO - Running pre-action listener: _resource_record_pre_action 14:21:56 INFO - Running main action method: run_tests 14:21:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:21:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:21:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:21:56 INFO - 2016-07-13 14:21:56.995 screenresolution[1644:7403] starting screenresolution argv=screenresolution get 14:21:56 INFO - 2016-07-13 14:21:56.998 screenresolution[1644:7403] Display 0: 1600x1200x32@60 14:21:57 INFO - 2016-07-13 14:21:57.003 screenresolution[1645:7405] starting screenresolution argv=screenresolution list 14:21:57 INFO - Available Modes on Display 0 14:21:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:21:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:21:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:21:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:21:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:21:57 INFO - Intel Iris: 14:21:57 INFO - Chipset Model: Intel Iris 14:21:57 INFO - Type: GPU 14:21:57 INFO - Bus: Built-In 14:21:57 INFO - VRAM (Dynamic, Max): 1536 MB 14:21:57 INFO - Vendor: Intel (0x8086) 14:21:57 INFO - Device ID: 0x0a2e 14:21:57 INFO - Revision ID: 0x0009 14:21:57 INFO - Displays: 14:21:57 INFO - Display: 14:21:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:21:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:21:57 INFO - Display Serial Number: 1600x1200 60 14:21:57 INFO - Main Display: Yes 14:21:57 INFO - Mirror: Off 14:21:57 INFO - Online: Yes 14:21:57 INFO - Rotation: Supported 14:21:57 INFO - Return code: 0 14:21:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:21:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:21:57 INFO - Python 2.7.3 14:21:57 INFO - Return code: 0 14:21:57 INFO - grabbing minidump binary from tooltool 14:21:57 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:21:57 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 0x10138ed30>, '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 0x1010f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101386b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:21:57 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 14:21:57 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 14:21:57 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 14:21:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:21:57 INFO - Return code: 0 14:21:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:21:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:21:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:21:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:21:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:21:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0256', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:21:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0256 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:21:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AC1ZWLbL6W/Render', 14:21:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:21:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:21:57 INFO - 'HOME': '/Users/cltbld', 14:21:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:21:57 INFO - 'LOGNAME': 'cltbld', 14:21:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:21:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:21:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:21:57 INFO - 'MOZ_NO_REMOTE': '1', 14:21:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:21:57 INFO - 'NO_EM_RESTART': '1', 14:21:57 INFO - 'PAGER': '/bin/cat', 14:21:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:21:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:21:57 INFO - 'PWD': '/builds/slave/test', 14:21:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:21:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:21:57 INFO - 'SHELL': '/bin/bash', 14:21:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XbE1jDHOCp/Listeners', 14:21:57 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 14:21:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:21:57 INFO - 'USER': 'cltbld', 14:21:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:21:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:21:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:21:57 INFO - 'XPC_FLAGS': '0x0', 14:21:57 INFO - 'XPC_SERVICE_NAME': '0', 14:21:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:21:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0256', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:21:57 INFO - mozversion application_buildid: 20160713120145 14:21:57 INFO - mozversion application_changeset: 9ce9896423df2ce58e0ec22486d6519d8221a362 14:21:57 INFO - mozversion application_display_name: Nightly 14:21:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:21:57 INFO - mozversion application_name: Firefox 14:21:57 INFO - mozversion application_remotingname: firefox 14:21:57 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:21:57 INFO - mozversion application_vendor: Mozilla 14:21:57 INFO - mozversion application_version: 50.0a1 14:21:57 INFO - mozversion platform_buildid: 20160713120145 14:21:57 INFO - mozversion platform_changeset: 9ce9896423df2ce58e0ec22486d6519d8221a362 14:21:57 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:21:57 INFO - mozversion platform_version: 50.0a1 14:21:57 INFO - using testdate: 1468444917 14:21:57 INFO - actual date: 1468444917 14:21:57 INFO - starting webserver on 'localhost:49279' 14:21:57 INFO - SUITE-START | Running 2 tests 14:21:57 INFO - TEST-START | dromaeo_css 14:21:57 INFO - operating with platform_type : mac_ 14:21:57 INFO - Initialising browser for dromaeo_css test... 14:21:57 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/tmpZPBKVU/profile http://localhost:49279/getInfo.html) 14:22:00 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 14:22:00 INFO - PROCESS | 1655 | colorDepth:24 14:22:00 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 14:22:00 INFO - PROCESS | 1655 | __metrics 14:22:00 INFO - PROCESS | 1655 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:22:00 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:22:01 INFO - TEST-INFO | 1655: exit 0 14:22:01 INFO - Browser initialized. 14:22:01 INFO - Running cycle 1/1 for dromaeo_css test... 14:22:01 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/tmpZPBKVU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:22:13 INFO - PROCESS | 1660 | RSS: Main: 243933184 14:22:13 INFO - PROCESS | 1660 | 14:23:03 INFO - PROCESS | 1660 | 1468444983904 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 14:25:15 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 14:25:16 INFO - PROCESS | 1660 | RSS: Main: 343568384 14:25:16 INFO - PROCESS | 1660 | 14:28:19 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 14:28:20 INFO - PROCESS | 1660 | RSS: Main: 380813312 14:28:20 INFO - PROCESS | 1660 | 14:31:22 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 14:31:23 INFO - PROCESS | 1660 | RSS: Main: 371699712 14:31:23 INFO - PROCESS | 1660 | 14:34:25 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 14:34:26 INFO - PROCESS | 1660 | RSS: Main: 371789824 14:34:26 INFO - PROCESS | 1660 | 14:37:29 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 14:37:30 INFO - PROCESS | 1660 | RSS: Main: 341917696 14:37:30 INFO - PROCESS | 1660 | 14:40:33 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 14:40:33 INFO - PROCESS | 1660 | RSS: Main: 381280256 14:40:33 INFO - PROCESS | 1660 | 14:40:33 INFO - PROCESS | 1660 | __start_tp_report 14:40:33 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 14:40:33 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 14:40:33 INFO - PROCESS | 1660 | |i|pagename|runs| 14:40:33 INFO - PROCESS | 1660 | |0;dojo.html;3190.9310381022356;3340.2818394045285;3361.4285571452856;3363.7645364824457;3422.794632322061;5428.674279543226;5606;5687.772489100442;5717.656940583563;5766.192733017376;5705.343885453174;5708.1152421374645;5787.074068149097;5796.159556864252;5828.795992140276;5125;5135.409427915782;5205.193195054772;5222.373315202162;5312.893742125155;5291.021161085192;5303.390110137326;5323.494268044529;5344.038073146832;5354.705491197982;5117.462666420047;5185.274061631375;5188.169892817169;5188.688678679291;5228.686278823264;4415.470143582758;5171.4311425743135;5178.378594568639;5181.274621552948;5248.475152484759;5156.690598564061;5182.53357197854;5193.584513238932;5240.318758561362;5249.606279529013;5588.161775733632;5641.68970706611;5702.885942281173;5758.424157584208;5776.306843178803;6128.411459112332;6214.440700336946;6217.129601855744;6222.81331560054;6254.312025677172;6208.87582248357;6244.843878903019;6248.31268560458;6254.124422580842;6284.088807122942;5655;5735.340435849847;5892.086726557391;5894.941050589525;5900.970495147497;6187.381261873777;6238.251409830849;6255.874882502325;6268.905966410507;6281.748730050838;6179.0422484514975;6187.381261873867;6192.504599632019;6206.565540412218;6215.223097112861;6095.801078121664;6153.538484613672;6217.813465596039;6249.875002500015;6268.686565671789;6195.4424101831055;6207.068939659087;6243.8126856194385;6255.78104766331;6262.780802671885;6242.1261023456755;6254.249490061131;6259.6870156492;6262.6868656566985;6263.3736626337;4889.413270407574;4921.138800709861;4926.852194434173;5020.5732512736095;5047.848564543069;4461.420015397977;4462.821487140543;4487.080148569536;4500.729956202638;4503.684742068024;5652.152177173457;5669.461401166883;5670.886582268332;5678.4037676044245;5696.917585658712;6207.317195108534;6255.624662520263;6263.592866463665;6279.403456671609;6288.5283603728985;5731.971340143273;5748.827535173951;5764.827055188351;5770.884582308414;5805.622634528742;5353.143497040533;5432.049391356576;5457.481539253765;5460.180972854104;5471.562275017989;6246.750129994749;6247.281562620356;6251.9687401562705;6258.499320054385;6263.060540918898;6227.906581401192;6274.247090349188;6280.780172693934;6298.716557755004;6305.148804911279;6178.9382106180165;6188.876222475616;6196.349383314868;6202.007678771375;6265.8120256392385;6214.844628884314;6222.222222222223;6225.688715564113;6237.812865614039;6241.126242326169;6284.4344009039405;6286.591371560834;6289.245290565162;6295.779647712308;6300.936990630218;5976.462118409364;6089.269287685329;6096.345267793707;6110.877782444415;6187.597806142587;6108.600674073149;6128.142060111499;6141.907871382022;6153.446189842935;6166.938330616816;5704.172894930129;6020.548458865515;6023.30731965812;6043.033114701627;6074.36219196978;5918;6104.725287362168;6116.594157862026;6140.232470941132;6150.446459818638;6006.459418652343;6008.068749343859;6008.519318454601;6014.127951447103;6060 14:40:33 INFO - PROCESS | 1660 | |1;ext.html;482.9809102793106;492.79795283933595;509.4701510429154;525.448279306728;538.5718353908647;29712.257193570145;31076.22309442265;31091.99999999997;31168.68831311686;40246.59753402465;34405;43416.91457713559;43477.34783978243;43525.564744352545;43606.563934360645;9520.809583808337;9702;9720.659776907809;9725.367851089657;9741.415515069084;8809.911900880974;8962.327825413087;9548;9667.178289845371;11843.993260572819;11715.53137874484;12044.879551204507;12068.336241506713;12079.973202277773;12082.939585302105;12028;12091.274523528617;12099.213551119194;12100;12106.455209515592;8743.387962842604;11524.19330646855;11731.296122232694;11739;11984.820227696591;41225.38161927574;41541.37687934683;41615.7919210405;41621;41707;3499.597546282185;3573.4639804029603;4006.4991876015497;4231.936520952188;4280.1653677532895;31520.684793152235;32399.51400728991;32731.6726832731;32765;32981.835090824636;3201.6158061032593;3366.4108780963234;3388.4578467445335;3402.0474267205223;3403.3533628610485;543106;543520;545534;548579;549649.999999996;58736.412635873094;58902.11646825301;59445.108323375185;59460.702696487104;59594.40405595888;62370.376296236456;62510;62569.68715156395;62655.6867215661;62714;15568.221588920509;15754;15767;15795.605109872346;15826.44607438734;11257.155713321534;12408.255504669749;12422.503099876085;12422.75154496905;12452.564160254347;13555.915526757835;13942.651433714238;13951.604839515967;14969.40122395092;15782.447614333443;5939.70301484924;6322.988321868479;6595.868082638321;6647.368499992493;6667.799966001027;862.7368652561029;917.2891009467716;973.4451362723321;1032.2877214721818;1103.3049179017328;29993.700062999527;30277.545836812464;30299;30825.537616935762;30871.382572348426;1090.8418279349607;1119.2892513254142;1143.937083460401;1185.8754830742648;1186.1044911092242;15712;15743.44897928567;15752.527424177293;16004.75992860108;16120;678527;683949;686977;687439;689510;51489.71275718137;58730.41269587335;59184.7040764802;59382.70308648429;63527.68236158882;1401.32736286583;1416.2635429576562;1437.4322142753763;1445.7831325301288;1445.8915581331232;30623.846880765454;31411.37177256488;31664.104765136366;31952.52071218887;32165.517517237728;49971.500284996684;50126.49873501218;50451.74774126253;50637.98724025572;50659.24011139762;496664;496870;497854;499019;499854;39079.264451488125;39275.01812454712;40019.19961600693;40187;40211.597884021954;47767.76116119397;48156.759216203696;48423.5157648419;49142;49178;37871.81064094662;39504.27725378446;39871.80064099661;40467.79766101151;40810.18379632331;38842.6115738839;39553.80223098866;39946.600533994286;40561.79719101504;40590;38287.42568861525;38643;39055.609443905196;39081.804590978;39217.80391098026;33938;34325.65674343224;34880;34986;36105.277894442486;22727;25525.61711574291;25680.486390271715;26182.214533564023;26426 14:40:33 INFO - PROCESS | 1660 | |2;jquery.html;367.1793541434891;384.78644352384424;395.0932609660829;397.7573680055166;417.65752083291693;15157.029586153798;16344;21713.2400365987;21758.673619895675;22238;21412.571748565057;21815.23646672367;22210;22240;22361.888190559024;5303.575713942875;5925.5555833312455;6026.246719160105;6035.366286539916;6072.967595508752;4234.428352172464;4469.396631454744;6066.39336066392;6190.040543715709;6547.541672082956;6758.966205168992;6825;6844.760433384832;6847.657617119149;6858.074159988412;6537.182852143482;6815.625140617264;6822.147231596051;6830.419414349736;6832.6242056687115;3739.719521035933;5571.4428557143965;5903.881922361528;5906.055031194988;5931.792387266425;27638.447231055266;27689;27831.860840695666;27957.580636290473;28062.579061313892;2239.7760223977634;2244.764299748519;2281.2243837095302;2366.597678394677;2479.429731161843;15710.454694992413;16298.592535186597;16481.67036659272;16806.411775587796;16866.241019154044;1884.2651365967147;1952.726618273443;1956.491312258823;1972.8125828046313;2139.1871088986086;117497.66274541136;122308;123189;125170;127235;9859.50702464874;11278.041366483776;11605.535778568932;11635.720070792206;11640.42854214687;14340.856591434162;14447.566573002827;14496.420143194366;14507.637309067357;14521.91085668579;8442.746717598482;8449.112843151504;8469.5341756204;8497.320214382835;8591.5704214789;8477.27943124829;8504.277136443472;8519.659213631508;8598.656053757779;8612.923384576929;3826.693864490834;4569.405977222939;4574.862754117382;4576.222042252825;4580.335851301609;1853.3698542495347;1896.1467339697192;1899.848012159024;1900.154431278068;1904.2478221102863;746.633660401464;874.6239117179674;896.9775755606162;902.444996327254;975.8243516167016;21018.684719729215;21701.457463563536;21780.78219217819;21800.563988720136;21830.79346308444;902.287193117443;929.1916033051153;945.1918609588703;947.5218658892013;975.8536219567121;10582.312149710244;10986.395748233765;11296.096312294996;11303.434828258554;11323.830142547868;586102;587091;598119;610149;612774;86242.56878715692;86421;137782.3110884439;140582;142768;967.2117224462041;999.3604093380243;1032.6798692405378;1034.5189486888632;1037.1650821088945;19547.413577592695;19957.800421996177;19965;19968.60062798765;19994.800051999293;32195.195120122185;53458.73270633622;54517.45482545124;58820.117698233706;59840;87672;91644;91725.08274917165;92101.53949230479;92974.74588389375;6238.656873871981;6997.265287144776;7517.473776835569;7977.92022079795;8317.833643326978;11014.39420831862;12904.870951290366;13035.608931732064;13534;13591.388387522387;11108.666739997812;11189.552417903518;11315.773684526099;11456.770864582828;11734.061275097802;11221.102311815199;11237.4381280934;11272.887271127183;11453.427328633368;11485.598004069818;11280.943595281971;11681.357525335849;11806;11911.166218364979;11977.161598688357;7492.1384040836;7559.735409260649;7583.2416758323725;7609.619519024137;7724.111727151225;11293.209475336647;11377.146713996317;11553.537858485903;11571.884281157418;11634.709132271422 14:40:33 INFO - PROCESS | 1660 | |3;mootools.html;1576.0779943732919;1604.2620394618475;1688.510332003719;1843.6128413033261;1967.3999430173792;7064.47016473764;8751.737447876565;9143.222826059784;9145.314101442393;9151.130642588943;7886.053673559167;8089.797755056126;8096.91903080969;8222.095569487352;8946.82106357874;2137.818285445739;2174.0760176924823;2318.698569186012;2391.9162829300976;2398.712154541449;2375.370526810388;2391.270662447953;2392.7726865947707;2393.868337241451;2397.9449042421365;2350.27141586108;2397.1609936522163;2404.194594810732;2405.7353691093967;2406.0496104038893;2119.63966125759;2317.2237300504353;2374.952500949971;2378.8691621960957;2389.7968672662846;2363.1728894886824;2370.8340416170877;2387.77316154965;2388.5103553771437;2390.940226494334;9096.63613455461;9188.26493880488;9194.95402522983;9201.53992300389;9214.90785092154;5328.440513746078;5418.8916221675345;5473.8357849264585;5487.396386397493;5492.368377636543;3051.893183738581;3111.222194451387;3576.1774791798034;3658.3414985302634;3662.2675464907124;1006.8238058339762;1037.906588407047;1052.0741747262437;1074.2802322443981;1074.376861420382;11930.343831089287;11942.880571194264;11982.820257696141;11991.22057066288;12050.517979280907;4618.792154353062;4709.293605959134;4855.975720121447;4857.587105096107;4867.415910090741;10247.436390998417;10258.487075646188;10262.589496420209;10268.486575671186;10273.537690803782;5479.780808767684;5480.232767412565;5484.890302194013;5486.533644640251;5487.835364939058;4919.77860996256;4927.211646136601;4933.432655244621;4996.3504744383;5002.974985125051;3704.8700146455053;3865.130345672201;3994.9001274967777;4065.654419374386;4138.5654506276405;1072.3244356055634;1235.7590269897464;1298.0718786068007;1310.6068179546098;1311.9737605248033;870.8693695945659;945.4469135555639;954.9761255968517;968.970930872075;1027.1269420992246;4828.734419606955;6104.4200800923845;6202.503799696014;6221.2845522765465;6226.782062627786;957.4973139101888;965.8744363232735;994.472929347447;1009.9444530550891;1020.1634659579222;4128.091819799699;4148;4163.167366526707;4217.198732240864;4248.978755106266;9184.12750788674;10629.787404251872;11120.054795342325;11139.05318047975;11145.275557088764;11067.169962252863;11070.889291107147;11089.390083545482;11120.38837863909;11133.66599002015;7926.24700653437;8291.626876790559;8327.50034997902;8351.958240208882;8378;6062;6113.663748493876;6247.781327653511;6268.811935641938;6408.1349017881985;9912.603495860085;9986.001399859955;10311.535980880728;10326.793464130826;10334.5349459273;10218.846717299395;10372.429516376671;10447.05976462122;10478.266521343432;10523.263371563917;7667.501612395176;7700.460967732205;7743.690252389842;7748.225177482207;7827.843443130992;8377.664893404195;8499.447535910145;8500.532470713923;8510.191531804468;8512.91487085146;6402.647854368054;7524.548527088392;7556.093268807778;7614.314711675976;7913.841723165389;7512.948187253898;7516.962415187889;7595.202503737028;7682.308592226726;7762.5730584818375;7164.534305270141;7195.748148814766;7246.5289756165685;7249.601271930094;7252.963735181291;4958.181899986462;5074.898502030012;5213.478652134908;5263.394709608291;5372.973135134299;5208.5051920067535;5282.445343238905;5322.920156197738;5411.242426060433;5422.186671999074 14:40:33 INFO - PROCESS | 1660 | |4;prototype.html;272.39935941249547;307.8599237347007;317.8140787639231;319.17493279871525;325.9967400325996;4424.358468022139;4779.545943135406;4959.429665588458;5437.238786569878;5468.2071099690575;5383.084875571152;5412.134058550623;5419.268398766166;5456.890862182754;5472.781088756455;3894;3922;4016.879493615196;4108.178364327132;4131.979340103296;4019.1358857845557;4069.8604390770633;4137.462129923106;4142.659397440437;4175.830767385122;3716.3310604091253;3898.610138986093;4056.7565946043183;4074.5925407459313;4117.958820411803;3368.4947257911263;3676.761010534307;3870.0711829161082;4076.8980775480554;4104.343305071204;3282.638909719929;3554.928901421957;3555.822208889545;3584.426491761332;3603;4729.527047295277;4902.975485122552;5251.86870328241;5254.973725131388;5337.386200586943;3962.5641179470235;4533.483916426382;4667.336141129197;4673.065386922629;4714.787834547454;4788.419821458945;5572.3870374258795;5652.349979752299;5653.971730141364;5676.205331253628;3903.082775547744;3930.508686414198;3932.7050471214775;3948.763074215556;3973.543042550086;4948.158813001798;5639.578071608966;5883.911741323926;5940.960331941893;6021.1269365942135;3329.883454079096;3739.6447337502896;3976.866593020975;4009;4012.8194231259663;4030.8589199378466;4042.7265411395315;4065.4918135233097;4124.876253712393;4134.627883490441;4457.122303595892;4723.504032076583;4728.479867214673;4740.288956656529;4765;4468.4414448193975;5044.394672639307;5495.34055913285;5774.82675519735;5790.334111577138;1278.0606254403006;1311.4623004568082;1388.8263967004125;1394.874461298488;1402.4810819996462;749.583980890611;867.3971589745066;875.3499653153851;876.5923845411786;896.2784958108655;5198.766055527511;5210.925553923204;5303;5311.814086507031;5322;4799.28010798383;5392.946070539323;5441;5790.594658373958;5834;4941.061198372297;5280.392754833243;5297.417284098746;5309.230161626542;5315.255864179018;2738.8493632850386;2896.6668833084045;2913.0969399485803;3002.264445210937;3043.52825312077;5513.751881165357;5521.420250873601;5791.681457519877;6008.639481631116;6032.879342413127;5129.102407078746;5169.147090729987;5503.807366742145;5953.732082056231;5997.570983751588;4797.496262892486;6052.001419765777;6081.263431455951;6109.297430795426;6173.197484327009;5730.856728581819;5903.7048147593305;5910.468057874812;5925.792597259076;5944.494717949023;4845.491223421631;5856.502197313276;5870.559708022;5926.822195334147;5934.10108765191;6081.5742897996715;6098.573099882966;6121.051237058263;6166.630002199882;6186.133941248138;3309.238875058798;3843.327417701835;3876.321643712283;3931.567527572022;3940.408938659224;5177.404598471224;5622.8754249149515;5679.778847547797;5838.795642152504;5897.498712609305;5495.890082198413;5682.886342273214;5704.605759573047;5731.799387021434;5800.607854115066;5306.893862122659;5701.771929122789;5717.285339332583;5732.656040637575;5741.224934633771;5375.59683023767;5581.9162712560155;5599.300087489064;5613.663180209201;5646.971765141148;5165.096108181128;5439.347278326626;5647.011772939802;5691.971540142273;5711.257536520226;5571.721413929368;5592.552595792408;5596.216529685766;5611.270534830446;5625.803096891589 14:40:33 INFO - PROCESS | 1660 | |5;yui.html;743.7322563877003;749.8837680159576;764.8929149919009;800.3997002248316;840.4325932136563;15196.924015379935;16572.171391430442;16597.004179749223;16658.83341166588;16676.249568769403;14904.478343257988;15120.924395378008;15157.924210378933;15164.165970871625;15188.620284492894;708.1997343002425;735.6799792090432;842.3892677808601;883.6862913665652;909.5543183839903;857.4083882121347;915.1626261970289;916.1617120334886;916.7295647783919;918.6830543462495;951.3103000324778;967.2310513142033;969.3844408800427;970.5292932927524;972.0134063925121;740.8888666699984;930.6603089872148;962.465831463541;964.0118878149917;968.2060710217625;717.8097804081923;718.3283629806148;719.3094629156017;719.6257945868172;719.910011248594;15339.539813805604;15342.386304547805;15359.539213823604;15360.078395296317;15360.846391536053;13588;13698.931505342509;13753;13909.860901390957;13958.720825583532;13003.804942925864;13017;13111.278879661615;13113.737725245443;13280.53518126861;4578.51925547816;4652.092841895832;4657.650676199299;4811;4827.106985207748;108972;110039.44980275127;110225.44887275592;111328.88671113267;111329;11276.32342059479;11348.546058157746;11367.261128026652;11382.772344553063;11768;23210.53578928412;23215.303540893463;23223.419414514774;23237.3028809136;23253.651195232083;7719;8108.027036755627;8180.918190818015;8277.58612069406;8384.455010424303;4509.571590698878;4528.615067719281;4554.225781617133;4700.788464519105;4721.834735784231;3539.3806083935724;3649.6532829381167;3649.817509124533;3650.178709790329;3658.2500587379523;516.4706176169382;519.3949049357457;528.3527678593692;531.8431062836472;539.4228175851879;567.3900556901348;571.9227904232959;633.4520639646672;641.6214433484266;674.3054653706592;8797.780055498535;9393.5303234838;9592.76018099553;9783.951080244695;9786.192426815922;588.3028611095865;636.8280564247627;661.5710066256862;683.1100785922574;688.7727050073464;4066.674666026711;4706.623470122382;4715.080559290974;4761.455170666078;4926.6551341406475;142226;145423.27288363702;147645.52354476534;147969;148826;88167.18328167261;111810.32284515738;116923.41538292254;118485.99999999828;118558;72421.27578724145;93019;100885.49557252314;102319.48840255752;105454.99999999847;8651.221390074923;8661.480311181349;8708.17272359125;8773.605187766694;9007.684731034382;59846.80306393935;59905.70047149737;60962.695186523786;61448;62043.689781550805;57918.710406447695;58057.70971145117;85069.1493085086;85240.14759852322;85339.5733021331;11519.19365644397;13734.725305494034;13739.381727822072;13944.651383715489;14249.358778854768;14980.700385992437;15087.773683394538;15399.230038498254;15516.301766420764;15720.60698482547;14204.502842400467;14482.782758258845;14503.459604758655;14587.927060365055;14619.561413157622;14345;14485.348159333065;14604.780928285873;14624.926875365554;14713.779293310823;13938.094023888414;13991.720165596835;14108.929455352658;14156.079854809404;14285.42858285657;9598.376105553116;11419.200655954335;11516.82724759146;11882.881171188177;11960.940195298968;9416.905830941603;11498.655040348804;11510.942445288056;11655.125865560287;11701.941490292495 14:40:33 INFO - PROCESS | 1660 | __end_tp_report 14:40:33 INFO - PROCESS | 1660 | __start_cc_report 14:40:33 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,1230 14:40:33 INFO - PROCESS | 1660 | __end_cc_report 14:40:33 INFO - PROCESS | 1660 | __startTimestamp1468446033370__endTimestamp 14:40:33 INFO - PROCESS | 1660 | 14:40:33 INFO - PROCESS | 1660 | ------- Summary: start ------- 14:40:33 INFO - PROCESS | 1660 | Number of tests: 6 14:40:33 INFO - PROCESS | 1660 | 14:40:33 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5755.40 Median:6020.55 stddev:643.92 (10.7%) stddev-sans-first:613.66 14:40:33 INFO - PROCESS | 1660 | Values: 3190.9 3340.3 3361.4 3363.8 3422.8 5428.7 5606.0 5687.8 5717.7 5766.2 5705.3 5708.1 5787.1 5796.2 5828.8 5125.0 5135.4 5205.2 5222.4 5312.9 5291.0 5303.4 5323.5 5344.0 5354.7 5117.5 5185.3 5188.2 5188.7 5228.7 4415.5 5171.4 5178.4 5181.3 5248.5 5156.7 5182.5 5193.6 5240.3 5249.6 5588.2 5641.7 5702.9 5758.4 5776.3 6128.4 6214.4 6217.1 6222.8 6254.3 6208.9 6244.8 6248.3 6254.1 6284.1 5655.0 5735.3 5892.1 5894.9 5901.0 6187.4 6238.3 6255.9 6268.9 6281.7 6179.0 6187.4 6192.5 6206.6 6215.2 6095.8 6153.5 6217.8 6249.9 6268.7 6195.4 6207.1 6243.8 6255.8 6262.8 6242.1 6254.2 6259.7 6262.7 6263.4 4889.4 4921.1 4926.9 5020.6 5047.8 4461.4 4462.8 4487.1 4500.7 4503.7 5652.2 5669.5 5670.9 5678.4 5696.9 6207.3 6255.6 6263.6 6279.4 6288.5 5732.0 5748.8 5764.8 5770.9 5805.6 5353.1 5432.0 5457.5 5460.2 5471.6 6246.8 6247.3 6252.0 6258.5 6263.1 6227.9 6274.2 6280.8 6298.7 6305.1 6178.9 6188.9 6196.3 6202.0 6265.8 6214.8 6222.2 6225.7 6237.8 6241.1 6284.4 6286.6 6289.2 6295.8 6300.9 5976.5 6089.3 6096.3 6110.9 6187.6 6108.6 6128.1 6141.9 6153.4 6166.9 5704.2 6020.5 6023.3 6043.0 6074.4 5918.0 6104.7 6116.6 6140.2 6150.4 6006.5 6008.1 6008.5 6014.1 6060.0 14:40:33 INFO - PROCESS | 1660 | 14:40:33 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:71333.58 Median:39149.80 stddev:155499.17 (397.2%) stddev-sans-first:155844.40 14:40:33 INFO - PROCESS | 1660 | Values: 483.0 492.8 509.5 525.4 538.6 29712.3 31076.2 31092.0 31168.7 40246.6 34405.0 43416.9 43477.3 43525.6 43606.6 9520.8 9702.0 9720.7 9725.4 9741.4 8809.9 8962.3 9548.0 9667.2 11844.0 11715.5 12044.9 12068.3 12080.0 12082.9 12028.0 12091.3 12099.2 12100.0 12106.5 8743.4 11524.2 11731.3 11739.0 11984.8 41225.4 41541.4 41615.8 41621.0 41707.0 3499.6 3573.5 4006.5 4231.9 4280.2 31520.7 32399.5 32731.7 32765.0 32981.8 3201.6 3366.4 3388.5 3402.0 3403.4 543106.0 543520.0 545534.0 548579.0 549650.0 58736.4 58902.1 59445.1 59460.7 59594.4 62370.4 62510.0 62569.7 62655.7 62714.0 15568.2 15754.0 15767.0 15795.6 15826.4 11257.2 12408.3 12422.5 12422.8 12452.6 13555.9 13942.7 13951.6 14969.4 15782.4 5939.7 6323.0 6595.9 6647.4 6667.8 862.7 917.3 973.4 1032.3 1103.3 29993.7 30277.5 30299.0 30825.5 30871.4 1090.8 1119.3 1143.9 1185.9 1186.1 15712.0 15743.4 15752.5 16004.8 16120.0 678527.0 683949.0 686977.0 687439.0 689510.0 51489.7 58730.4 59184.7 59382.7 63527.7 1401.3 1416.3 1437.4 1445.8 1445.9 30623.8 31411.4 31664.1 31952.5 32165.5 49971.5 50126.5 50451.7 50638.0 50659.2 496664.0 496870.0 497854.0 499019.0 499854.0 39079.3 39275.0 40019.2 40187.0 40211.6 47767.8 48156.8 48423.5 49142.0 49178.0 37871.8 39504.3 39871.8 40467.8 40810.2 38842.6 39553.8 39946.6 40561.8 40590.0 38287.4 38643.0 39055.6 39081.8 39217.8 33938.0 34325.7 34880.0 34986.0 36105.3 22727.0 25525.6 25680.5 26182.2 26426.0 14:40:33 INFO - PROCESS | 1660 | 14:40:33 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:35629.52 Median:27663.72 stddev:100073.64 (361.8%) stddev-sans-first:100319.35 14:40:33 INFO - PROCESS | 1660 | Values: 367.2 384.8 395.1 397.8 417.7 15157.0 16344.0 21713.2 21758.7 22238.0 21412.6 21815.2 22210.0 22240.0 22361.9 5303.6 5925.6 6026.2 6035.4 6073.0 4234.4 4469.4 6066.4 6190.0 6547.5 6759.0 6825.0 6844.8 6847.7 6858.1 6537.2 6815.6 6822.1 6830.4 6832.6 3739.7 5571.4 5903.9 5906.1 5931.8 27638.4 27689.0 27831.9 27957.6 28062.6 2239.8 2244.8 2281.2 2366.6 2479.4 15710.5 16298.6 16481.7 16806.4 16866.2 1884.3 1952.7 1956.5 1972.8 2139.2 117497.7 122308.0 123189.0 125170.0 127235.0 9859.5 11278.0 11605.5 11635.7 11640.4 14340.9 14447.6 14496.4 14507.6 14521.9 8442.7 8449.1 8469.5 8497.3 8591.6 8477.3 8504.3 8519.7 8598.7 8612.9 3826.7 4569.4 4574.9 4576.2 4580.3 1853.4 1896.1 1899.8 1900.2 1904.2 746.6 874.6 897.0 902.4 975.8 21018.7 21701.5 21780.8 21800.6 21830.8 902.3 929.2 945.2 947.5 975.9 10582.3 10986.4 11296.1 11303.4 11323.8 586102.0 587091.0 598119.0 610149.0 612774.0 86242.6 86421.0 137782.3 140582.0 142768.0 967.2 999.4 1032.7 1034.5 1037.2 19547.4 19957.8 19965.0 19968.6 19994.8 32195.2 53458.7 54517.5 58820.1 59840.0 87672.0 91644.0 91725.1 92101.5 92974.7 6238.7 6997.3 7517.5 7977.9 8317.8 11014.4 12904.9 13035.6 13534.0 13591.4 11108.7 11189.6 11315.8 11456.8 11734.1 11221.1 11237.4 11272.9 11453.4 11485.6 11280.9 11681.4 11806.0 11911.2 11977.2 7492.1 7559.7 7583.2 7609.6 7724.1 11293.2 11377.1 11553.5 11571.9 11634.7 14:40:33 INFO - PROCESS | 1660 | 14:40:33 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5831.74 Median:4893.60 stddev:3270.65 (66.8%) stddev-sans-first:3264.19 14:40:33 INFO - PROCESS | 1660 | Values: 1576.1 1604.3 1688.5 1843.6 1967.4 7064.5 8751.7 9143.2 9145.3 9151.1 7886.1 8089.8 8096.9 8222.1 8946.8 2137.8 2174.1 2318.7 2391.9 2398.7 2375.4 2391.3 2392.8 2393.9 2397.9 2350.3 2397.2 2404.2 2405.7 2406.0 2119.6 2317.2 2375.0 2378.9 2389.8 2363.2 2370.8 2387.8 2388.5 2390.9 9096.6 9188.3 9195.0 9201.5 9214.9 5328.4 5418.9 5473.8 5487.4 5492.4 3051.9 3111.2 3576.2 3658.3 3662.3 1006.8 1037.9 1052.1 1074.3 1074.4 11930.3 11942.9 11982.8 11991.2 12050.5 4618.8 4709.3 4856.0 4857.6 4867.4 10247.4 10258.5 10262.6 10268.5 10273.5 5479.8 5480.2 5484.9 5486.5 5487.8 4919.8 4927.2 4933.4 4996.4 5003.0 3704.9 3865.1 3994.9 4065.7 4138.6 1072.3 1235.8 1298.1 1310.6 1312.0 870.9 945.4 955.0 969.0 1027.1 4828.7 6104.4 6202.5 6221.3 6226.8 957.5 965.9 994.5 1009.9 1020.2 4128.1 4148.0 4163.2 4217.2 4249.0 9184.1 10629.8 11120.1 11139.1 11145.3 11067.2 11070.9 11089.4 11120.4 11133.7 7926.2 8291.6 8327.5 8352.0 8378.0 6062.0 6113.7 6247.8 6268.8 6408.1 9912.6 9986.0 10311.5 10326.8 10334.5 10218.8 10372.4 10447.1 10478.3 10523.3 7667.5 7700.5 7743.7 7748.2 7827.8 8377.7 8499.4 8500.5 8510.2 8512.9 6402.6 7524.5 7556.1 7614.3 7913.8 7512.9 7517.0 7595.2 7682.3 7762.6 7164.5 7195.7 7246.5 7249.6 7253.0 4958.2 5074.9 5213.5 5263.4 5373.0 5208.5 5282.4 5322.9 5411.2 5422.2 14:40:33 INFO - PROCESS | 1660 | 14:40:33 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:4615.48 Median:5267.68 stddev:1426.90 (27.1%) stddev-sans-first:1393.17 14:40:33 INFO - PROCESS | 1660 | Values: 272.4 307.9 317.8 319.2 326.0 4424.4 4779.5 4959.4 5437.2 5468.2 5383.1 5412.1 5419.3 5456.9 5472.8 3894.0 3922.0 4016.9 4108.2 4132.0 4019.1 4069.9 4137.5 4142.7 4175.8 3716.3 3898.6 4056.8 4074.6 4118.0 3368.5 3676.8 3870.1 4076.9 4104.3 3282.6 3554.9 3555.8 3584.4 3603.0 4729.5 4903.0 5251.9 5255.0 5337.4 3962.6 4533.5 4667.3 4673.1 4714.8 4788.4 5572.4 5652.3 5654.0 5676.2 3903.1 3930.5 3932.7 3948.8 3973.5 4948.2 5639.6 5883.9 5941.0 6021.1 3329.9 3739.6 3976.9 4009.0 4012.8 4030.9 4042.7 4065.5 4124.9 4134.6 4457.1 4723.5 4728.5 4740.3 4765.0 4468.4 5044.4 5495.3 5774.8 5790.3 1278.1 1311.5 1388.8 1394.9 1402.5 749.6 867.4 875.3 876.6 896.3 5198.8 5210.9 5303.0 5311.8 5322.0 4799.3 5392.9 5441.0 5790.6 5834.0 4941.1 5280.4 5297.4 5309.2 5315.3 2738.8 2896.7 2913.1 3002.3 3043.5 5513.8 5521.4 5791.7 6008.6 6032.9 5129.1 5169.1 5503.8 5953.7 5997.6 4797.5 6052.0 6081.3 6109.3 6173.2 5730.9 5903.7 5910.5 5925.8 5944.5 4845.5 5856.5 5870.6 5926.8 5934.1 6081.6 6098.6 6121.1 6166.6 6186.1 3309.2 3843.3 3876.3 3931.6 3940.4 5177.4 5622.9 5679.8 5838.8 5897.5 5495.9 5682.9 5704.6 5731.8 5800.6 5306.9 5701.8 5717.3 5732.7 5741.2 5375.6 5581.9 5599.3 5613.7 5647.0 5165.1 5439.3 5647.0 5692.0 5711.3 5571.7 5592.6 5596.2 5611.3 5625.8 14:40:33 INFO - PROCESS | 1660 | 14:40:33 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:23641.41 Median:4288.12 stddev:36549.07 (852.3%) stddev-sans-first:36611.16 14:40:33 INFO - PROCESS | 1660 | Values: 743.7 749.9 764.9 800.4 840.4 15196.9 16572.2 16597.0 16658.8 16676.2 14904.5 15120.9 15157.9 15164.2 15188.6 708.2 735.7 842.4 883.7 909.6 857.4 915.2 916.2 916.7 918.7 951.3 967.2 969.4 970.5 972.0 740.9 930.7 962.5 964.0 968.2 717.8 718.3 719.3 719.6 719.9 15339.5 15342.4 15359.5 15360.1 15360.8 13588.0 13698.9 13753.0 13909.9 13958.7 13003.8 13017.0 13111.3 13113.7 13280.5 4578.5 4652.1 4657.7 4811.0 4827.1 108972.0 110039.4 110225.4 111328.9 111329.0 11276.3 11348.5 11367.3 11382.8 11768.0 23210.5 23215.3 23223.4 23237.3 23253.7 7719.0 8108.0 8180.9 8277.6 8384.5 4509.6 4528.6 4554.2 4700.8 4721.8 3539.4 3649.7 3649.8 3650.2 3658.3 516.5 519.4 528.4 531.8 539.4 567.4 571.9 633.5 641.6 674.3 8797.8 9393.5 9592.8 9784.0 9786.2 588.3 636.8 661.6 683.1 688.8 4066.7 4706.6 4715.1 4761.5 4926.7 142226.0 145423.3 147645.5 147969.0 148826.0 88167.2 111810.3 116923.4 118486.0 118558.0 72421.3 93019.0 100885.5 102319.5 105455.0 8651.2 8661.5 8708.2 8773.6 9007.7 59846.8 59905.7 60962.7 61448.0 62043.7 57918.7 58057.7 85069.1 85240.1 85339.6 11519.2 13734.7 13739.4 13944.7 14249.4 14980.7 15087.8 15399.2 15516.3 15720.6 14204.5 14482.8 14503.5 14587.9 14619.6 14345.0 14485.3 14604.8 14624.9 14713.8 13938.1 13991.7 14108.9 14156.1 14285.4 9598.4 11419.2 11516.8 11882.9 11960.9 9416.9 11498.7 11510.9 11655.1 11701.9 14:40:33 INFO - PROCESS | 1660 | -------- Summary: end -------- 14:40:33 INFO - PROCESS | 1660 | 14:40:33 INFO - TEST-INFO | 1660: exit 0 14:40:33 INFO - TEST-OK | dromaeo_css | took 1116405ms 14:40:33 INFO - TEST-START | kraken 14:40:33 INFO - operating with platform_type : mac_ 14:40:33 INFO - Initialising browser for kraken test... 14:40:34 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/tmpubxvlO/profile http://localhost:49279/getInfo.html) 14:40:36 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 14:40:36 INFO - PROCESS | 1668 | colorDepth:24 14:40:36 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 14:40:36 INFO - PROCESS | 1668 | __metrics 14:40:36 INFO - PROCESS | 1668 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:40:36 INFO - PROCESS | 1668 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:40:36 INFO - TEST-INFO | 1668: exit 0 14:40:36 INFO - Browser initialized. 14:40:36 INFO - Running cycle 1/1 for kraken test... 14:40:36 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/tmpubxvlO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:40:48 INFO - PROCESS | 1669 | RSS: Main: 243765248 14:40:48 INFO - PROCESS | 1669 | 14:40:51 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 14:40:52 INFO - PROCESS | 1669 | RSS: Main: 293982208 14:40:52 INFO - PROCESS | 1669 | 14:40:54 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 14:40:55 INFO - PROCESS | 1669 | RSS: Main: 304521216 14:40:55 INFO - PROCESS | 1669 | 14:40:58 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 14:40:59 INFO - PROCESS | 1669 | RSS: Main: 388587520 14:40:59 INFO - PROCESS | 1669 | 14:41:01 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 14:41:02 INFO - PROCESS | 1669 | RSS: Main: 290000896 14:41:02 INFO - PROCESS | 1669 | 14:41:04 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:41:04 INFO - PROCESS | 1669 | RSS: Main: 293744640 14:41:04 INFO - PROCESS | 1669 | 14:41:07 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 14:41:08 INFO - PROCESS | 1669 | RSS: Main: 328310784 14:41:08 INFO - PROCESS | 1669 | 14:41:11 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 14:41:11 INFO - PROCESS | 1669 | RSS: Main: 333078528 14:41:11 INFO - PROCESS | 1669 | 14:41:14 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 14:41:15 INFO - PROCESS | 1669 | RSS: Main: 332689408 14:41:15 INFO - PROCESS | 1669 | 14:41:16 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:41:17 INFO - PROCESS | 1669 | RSS: Main: 311394304 14:41:17 INFO - PROCESS | 1669 | 14:41:19 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:41:19 INFO - PROCESS | 1669 | RSS: Main: 318849024 14:41:19 INFO - PROCESS | 1669 | 14:41:22 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:41:22 INFO - PROCESS | 1669 | RSS: Main: 315768832 14:41:22 INFO - PROCESS | 1669 | 14:41:25 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:41:26 INFO - PROCESS | 1669 | RSS: Main: 324575232 14:41:26 INFO - PROCESS | 1669 | 14:41:29 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:41:29 INFO - PROCESS | 1669 | RSS: Main: 327024640 14:41:29 INFO - PROCESS | 1669 | 14:41:31 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 14:41:31 INFO - PROCESS | 1669 | RSS: Main: 370769920 14:41:31 INFO - PROCESS | 1669 | 14:41:31 INFO - PROCESS | 1669 | __start_tp_report 14:41:31 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 14:41:31 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 14:41:31 INFO - PROCESS | 1669 | |i|pagename|runs| 14:41:31 INFO - PROCESS | 1669 | |0;ai-astar;102;111;113;112;115;113;114;113;113;117 14:41:31 INFO - PROCESS | 1669 | |1;audio-beat-detection;141;108;106;154;105;104;125;104;138;105 14:41:31 INFO - PROCESS | 1669 | |2;audio-dft;183;185;186;184;185;182;183;183;183;185 14:41:31 INFO - PROCESS | 1669 | |3;audio-fft;79;76;57;106;57;57;60;59;128;59 14:41:31 INFO - PROCESS | 1669 | |4;audio-oscillator;82;83;92;70;74;68;95;69;67;98 14:41:31 INFO - PROCESS | 1669 | |5;imaging-gaussian-blur;89;83;86;84;87;84;86;84;87;83 14:41:31 INFO - PROCESS | 1669 | |6;imaging-darkroom;93;94;94;106;147;120;94;93;93;92 14:41:31 INFO - PROCESS | 1669 | |7;imaging-desaturate;82;119;137;93;87;86;93;86;86;87 14:41:31 INFO - PROCESS | 1669 | |8;json-parse-financial;55;54;55;57;57;53;54;52;54;54 14:41:31 INFO - PROCESS | 1669 | |9;json-stringify-tinderbox;67;81;77;73;71;67;69;72;70;70 14:41:31 INFO - PROCESS | 1669 | |10;stanford-crypto-aes;80;84;81;82;82;84;83;83;85;82 14:41:31 INFO - PROCESS | 1669 | |11;stanford-crypto-ccm;134;136;135;134;134;136;126;126;228;152 14:41:31 INFO - PROCESS | 1669 | |12;stanford-crypto-pbkdf2;165;163;163;163;162;160;158;160;160;160 14:41:31 INFO - PROCESS | 1669 | |13;stanford-crypto-sha256-iterative;66;67;67;65;66;66;67;65;64;64 14:41:31 INFO - PROCESS | 1669 | __end_tp_report 14:41:31 INFO - PROCESS | 1669 | __start_cc_report 14:41:31 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,2007 14:41:31 INFO - PROCESS | 1669 | __end_cc_report 14:41:31 INFO - PROCESS | 1669 | __startTimestamp1468446091592__endTimestamp 14:41:31 INFO - PROCESS | 1669 | 14:41:31 INFO - PROCESS | 1669 | ------- Summary: start ------- 14:41:31 INFO - PROCESS | 1669 | Number of tests: 14 14:41:31 INFO - PROCESS | 1669 | 14:41:31 INFO - PROCESS | 1669 | [#0] ai-astar Cycles:10 Average:112.30 Median:113.00 stddev:3.97 (3.5%) stddev-sans-first:1.74 14:41:31 INFO - PROCESS | 1669 | Values: 102.0 111.0 113.0 112.0 115.0 113.0 114.0 113.0 113.0 117.0 14:41:31 INFO - PROCESS | 1669 | 14:41:31 INFO - PROCESS | 1669 | [#1] audio-beat-detection Cycles:10 Average:119.00 Median:116.50 stddev:18.97 (16.3%) stddev-sans-first:18.37 14:41:31 INFO - PROCESS | 1669 | Values: 141.0 108.0 106.0 154.0 105.0 104.0 125.0 104.0 138.0 105.0 14:41:31 INFO - PROCESS | 1669 | 14:41:31 INFO - PROCESS | 1669 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.50 stddev:1.29 (0.7%) stddev-sans-first:1.32 14:41:31 INFO - PROCESS | 1669 | Values: 183.0 185.0 186.0 184.0 185.0 182.0 183.0 183.0 183.0 185.0 14:41:31 INFO - PROCESS | 1669 | 14:41:31 INFO - PROCESS | 1669 | [#3] audio-fft Cycles:10 Average:73.80 Median:59.00 stddev:24.68 (41.8%) stddev-sans-first:26.10 14:41:31 INFO - PROCESS | 1669 | Values: 79.0 76.0 57.0 106.0 57.0 57.0 60.0 59.0 128.0 59.0 14:41:31 INFO - PROCESS | 1669 | 14:41:31 INFO - PROCESS | 1669 | [#4] audio-oscillator Cycles:10 Average:79.80 Median:82.50 stddev:11.91 (14.4%) stddev-sans-first:12.60 14:41:31 INFO - PROCESS | 1669 | Values: 82.0 83.0 92.0 70.0 74.0 68.0 95.0 69.0 67.0 98.0 14:41:31 INFO - PROCESS | 1669 | 14:41:31 INFO - PROCESS | 1669 | [#5] imaging-gaussian-blur Cycles:10 Average:85.30 Median:86.00 stddev:2.00 (2.3%) stddev-sans-first:1.62 14:41:31 INFO - PROCESS | 1669 | Values: 89.0 83.0 86.0 84.0 87.0 84.0 86.0 84.0 87.0 83.0 14:41:31 INFO - PROCESS | 1669 | 14:41:31 INFO - PROCESS | 1669 | [#6] imaging-darkroom Cycles:10 Average:102.60 Median:93.00 stddev:17.94 (19.3%) stddev-sans-first:18.69 14:41:31 INFO - PROCESS | 1669 | Values: 93.0 94.0 94.0 106.0 147.0 120.0 94.0 93.0 93.0 92.0 14:41:31 INFO - PROCESS | 1669 | 14:41:31 INFO - PROCESS | 1669 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:86.50 stddev:17.90 (20.7%) stddev-sans-first:18.30 14:41:31 INFO - PROCESS | 1669 | Values: 82.0 119.0 137.0 93.0 87.0 86.0 93.0 86.0 86.0 87.0 14:41:31 INFO - PROCESS | 1669 | 14:41:31 INFO - PROCESS | 1669 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:54.50 stddev:1.58 (2.9%) stddev-sans-first:1.67 14:41:31 INFO - PROCESS | 1669 | Values: 55.0 54.0 55.0 57.0 57.0 53.0 54.0 52.0 54.0 54.0 14:41:31 INFO - PROCESS | 1669 | 14:41:31 INFO - PROCESS | 1669 | [#9] json-stringify-tinderbox Cycles:10 Average:71.70 Median:71.50 stddev:4.40 (6.2%) stddev-sans-first:4.32 14:41:31 INFO - PROCESS | 1669 | Values: 67.0 81.0 77.0 73.0 71.0 67.0 69.0 72.0 70.0 70.0 14:41:31 INFO - PROCESS | 1669 | 14:41:31 INFO - PROCESS | 1669 | [#10] stanford-crypto-aes Cycles:10 Average:82.60 Median:83.00 stddev:1.51 (1.8%) stddev-sans-first:1.27 14:41:31 INFO - PROCESS | 1669 | Values: 80.0 84.0 81.0 82.0 82.0 84.0 83.0 83.0 85.0 82.0 14:41:31 INFO - PROCESS | 1669 | 14:41:31 INFO - PROCESS | 1669 | [#11] stanford-crypto-ccm Cycles:10 Average:144.10 Median:135.50 stddev:30.33 (22.4%) stddev-sans-first:31.94 14:41:31 INFO - PROCESS | 1669 | Values: 134.0 136.0 135.0 134.0 134.0 136.0 126.0 126.0 228.0 152.0 14:41:31 INFO - PROCESS | 1669 | 14:41:31 INFO - PROCESS | 1669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.40 Median:162.50 stddev:2.12 (1.3%) stddev-sans-first:1.80 14:41:31 INFO - PROCESS | 1669 | Values: 165.0 163.0 163.0 163.0 162.0 160.0 158.0 160.0 160.0 160.0 14:41:31 INFO - PROCESS | 1669 | 14:41:31 INFO - PROCESS | 1669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.00 stddev:1.16 (1.8%) stddev-sans-first:1.22 14:41:31 INFO - PROCESS | 1669 | Values: 66.0 67.0 67.0 65.0 66.0 66.0 67.0 65.0 64.0 64.0 14:41:31 INFO - PROCESS | 1669 | -------- Summary: end -------- 14:41:31 INFO - PROCESS | 1669 | 14:41:32 INFO - TEST-INFO | 1669: exit 0 14:41:32 INFO - TEST-OK | kraken | took 58505ms 14:41:32 INFO - SUITE-END | took 1174s 14:41:32 INFO - Completed test suite (00:19:35) 14:41:32 INFO - javascript benchmark 14:41:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3190.9310381022356, 3340.2818394045285, 3361.4285571452856, 3363.7645364824457, 3422.794632322061, 5428.674279543226, 5606.0, 5687.772489100442, 5717.656940583563, 5766.192733017376, 5705.343885453174, 5708.1152421374645, 5787.074068149097, 5796.159556864252, 5828.795992140276, 5125.0, 5135.409427915782, 5205.193195054772, 5222.373315202162, 5312.893742125155, 5291.021161085192, 5303.390110137326, 5323.494268044529, 5344.038073146832, 5354.705491197982, 5117.462666420047, 5185.274061631375, 5188.169892817169, 5188.688678679291, 5228.686278823264, 4415.470143582758, 5171.4311425743135, 5178.378594568639, 5181.274621552948, 5248.475152484759, 5156.690598564061, 5182.53357197854, 5193.584513238932, 5240.318758561362, 5249.606279529013, 5588.161775733632, 5641.68970706611, 5702.885942281173, 5758.424157584208, 5776.306843178803, 6128.411459112332, 6214.440700336946, 6217.129601855744, 6222.81331560054, 6254.312025677172, 6208.87582248357, 6244.843878903019, 6248.31268560458, 6254.124422580842, 6284.088807122942, 5655.0, 5735.340435849847, 5892.086726557391, 5894.941050589525, 5900.970495147497, 6187.381261873777, 6238.251409830849, 6255.874882502325, 6268.905966410507, 6281.748730050838, 6179.0422484514975, 6187.381261873867, 6192.504599632019, 6206.565540412218, 6215.223097112861, 6095.801078121664, 6153.538484613672, 6217.813465596039, 6249.875002500015, 6268.686565671789, 6195.4424101831055, 6207.068939659087, 6243.8126856194385, 6255.78104766331, 6262.780802671885, 6242.1261023456755, 6254.249490061131, 6259.6870156492, 6262.6868656566985, 6263.3736626337, 4889.413270407574, 4921.138800709861, 4926.852194434173, 5020.5732512736095, 5047.848564543069, 4461.420015397977, 4462.821487140543, 4487.080148569536, 4500.729956202638, 4503.684742068024, 5652.152177173457, 5669.461401166883, 5670.886582268332, 5678.4037676044245, 5696.917585658712, 6207.317195108534, 6255.624662520263, 6263.592866463665, 6279.403456671609, 6288.5283603728985, 5731.971340143273, 5748.827535173951, 5764.827055188351, 5770.884582308414, 5805.622634528742, 5353.143497040533, 5432.049391356576, 5457.481539253765, 5460.180972854104, 5471.562275017989, 6246.750129994749, 6247.281562620356, 6251.9687401562705, 6258.499320054385, 6263.060540918898, 6227.906581401192, 6274.247090349188, 6280.780172693934, 6298.716557755004, 6305.148804911279, 6178.9382106180165, 6188.876222475616, 6196.349383314868, 6202.007678771375, 6265.8120256392385, 6214.844628884314, 6222.222222222223, 6225.688715564113, 6237.812865614039, 6241.126242326169, 6284.4344009039405, 6286.591371560834, 6289.245290565162, 6295.779647712308, 6300.936990630218, 5976.462118409364, 6089.269287685329, 6096.345267793707, 6110.877782444415, 6187.597806142587, 6108.600674073149, 6128.142060111499, 6141.907871382022, 6153.446189842935, 6166.938330616816, 5704.172894930129, 6020.548458865515, 6023.30731965812, 6043.033114701627, 6074.36219196978, 5918.0, 6104.725287362168, 6116.594157862026, 6140.232470941132, 6150.446459818638, 6006.459418652343, 6008.068749343859, 6008.519318454601, 6014.127951447103, 6060.0], "value": 5713.089804790548, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [482.9809102793106, 492.79795283933595, 509.4701510429154, 525.448279306728, 538.5718353908647, 29712.257193570145, 31076.22309442265, 31091.99999999997, 31168.68831311686, 40246.59753402465, 34405.0, 43416.91457713559, 43477.34783978243, 43525.564744352545, 43606.563934360645, 9520.809583808337, 9702.0, 9720.659776907809, 9725.367851089657, 9741.415515069084, 8809.911900880974, 8962.327825413087, 9548.0, 9667.178289845371, 11843.993260572819, 11715.53137874484, 12044.879551204507, 12068.336241506713, 12079.973202277773, 12082.939585302105, 12028.0, 12091.274523528617, 12099.213551119194, 12100.0, 12106.455209515592, 8743.387962842604, 11524.19330646855, 11731.296122232694, 11739.0, 11984.820227696591, 41225.38161927574, 41541.37687934683, 41615.7919210405, 41621.0, 41707.0, 3499.597546282185, 3573.4639804029603, 4006.4991876015497, 4231.936520952188, 4280.1653677532895, 31520.684793152235, 32399.51400728991, 32731.6726832731, 32765.0, 32981.835090824636, 3201.6158061032593, 3366.4108780963234, 3388.4578467445335, 3402.0474267205223, 3403.3533628610485, 543106.0, 543520.0, 545534.0, 548579.0, 549649.999999996, 58736.412635873094, 58902.11646825301, 59445.108323375185, 59460.702696487104, 59594.40405595888, 62370.376296236456, 62510.0, 62569.68715156395, 62655.6867215661, 62714.0, 15568.221588920509, 15754.0, 15767.0, 15795.605109872346, 15826.44607438734, 11257.155713321534, 12408.255504669749, 12422.503099876085, 12422.75154496905, 12452.564160254347, 13555.915526757835, 13942.651433714238, 13951.604839515967, 14969.40122395092, 15782.447614333443, 5939.70301484924, 6322.988321868479, 6595.868082638321, 6647.368499992493, 6667.799966001027, 862.7368652561029, 917.2891009467716, 973.4451362723321, 1032.2877214721818, 1103.3049179017328, 29993.700062999527, 30277.545836812464, 30299.0, 30825.537616935762, 30871.382572348426, 1090.8418279349607, 1119.2892513254142, 1143.937083460401, 1185.8754830742648, 1186.1044911092242, 15712.0, 15743.44897928567, 15752.527424177293, 16004.75992860108, 16120.0, 678527.0, 683949.0, 686977.0, 687439.0, 689510.0, 51489.71275718137, 58730.41269587335, 59184.7040764802, 59382.70308648429, 63527.68236158882, 1401.32736286583, 1416.2635429576562, 1437.4322142753763, 1445.7831325301288, 1445.8915581331232, 30623.846880765454, 31411.37177256488, 31664.104765136366, 31952.52071218887, 32165.517517237728, 49971.500284996684, 50126.49873501218, 50451.74774126253, 50637.98724025572, 50659.24011139762, 496664.0, 496870.0, 497854.0, 499019.0, 499854.0, 39079.264451488125, 39275.01812454712, 40019.19961600693, 40187.0, 40211.597884021954, 47767.76116119397, 48156.759216203696, 48423.5157648419, 49142.0, 49178.0, 37871.81064094662, 39504.27725378446, 39871.80064099661, 40467.79766101151, 40810.18379632331, 38842.6115738839, 39553.80223098866, 39946.600533994286, 40561.79719101504, 40590.0, 38287.42568861525, 38643.0, 39055.609443905196, 39081.804590978, 39217.80391098026, 33938.0, 34325.65674343224, 34880.0, 34986.0, 36105.277894442486, 22727.0, 25525.61711574291, 25680.486390271715, 26182.214533564023, 26426.0], "value": 20526.643507937162, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.1793541434891, 384.78644352384424, 395.0932609660829, 397.7573680055166, 417.65752083291693, 15157.029586153798, 16344.0, 21713.2400365987, 21758.673619895675, 22238.0, 21412.571748565057, 21815.23646672367, 22210.0, 22240.0, 22361.888190559024, 5303.575713942875, 5925.5555833312455, 6026.246719160105, 6035.366286539916, 6072.967595508752, 4234.428352172464, 4469.396631454744, 6066.39336066392, 6190.040543715709, 6547.541672082956, 6758.966205168992, 6825.0, 6844.760433384832, 6847.657617119149, 6858.074159988412, 6537.182852143482, 6815.625140617264, 6822.147231596051, 6830.419414349736, 6832.6242056687115, 3739.719521035933, 5571.4428557143965, 5903.881922361528, 5906.055031194988, 5931.792387266425, 27638.447231055266, 27689.0, 27831.860840695666, 27957.580636290473, 28062.579061313892, 2239.7760223977634, 2244.764299748519, 2281.2243837095302, 2366.597678394677, 2479.429731161843, 15710.454694992413, 16298.592535186597, 16481.67036659272, 16806.411775587796, 16866.241019154044, 1884.2651365967147, 1952.726618273443, 1956.491312258823, 1972.8125828046313, 2139.1871088986086, 117497.66274541136, 122308.0, 123189.0, 125170.0, 127235.0, 9859.50702464874, 11278.041366483776, 11605.535778568932, 11635.720070792206, 11640.42854214687, 14340.856591434162, 14447.566573002827, 14496.420143194366, 14507.637309067357, 14521.91085668579, 8442.746717598482, 8449.112843151504, 8469.5341756204, 8497.320214382835, 8591.5704214789, 8477.27943124829, 8504.277136443472, 8519.659213631508, 8598.656053757779, 8612.923384576929, 3826.693864490834, 4569.405977222939, 4574.862754117382, 4576.222042252825, 4580.335851301609, 1853.3698542495347, 1896.1467339697192, 1899.848012159024, 1900.154431278068, 1904.2478221102863, 746.633660401464, 874.6239117179674, 896.9775755606162, 902.444996327254, 975.8243516167016, 21018.684719729215, 21701.457463563536, 21780.78219217819, 21800.563988720136, 21830.79346308444, 902.287193117443, 929.1916033051153, 945.1918609588703, 947.5218658892013, 975.8536219567121, 10582.312149710244, 10986.395748233765, 11296.096312294996, 11303.434828258554, 11323.830142547868, 586102.0, 587091.0, 598119.0, 610149.0, 612774.0, 86242.56878715692, 86421.0, 137782.3110884439, 140582.0, 142768.0, 967.2117224462041, 999.3604093380243, 1032.6798692405378, 1034.5189486888632, 1037.1650821088945, 19547.413577592695, 19957.800421996177, 19965.0, 19968.60062798765, 19994.800051999293, 32195.195120122185, 53458.73270633622, 54517.45482545124, 58820.117698233706, 59840.0, 87672.0, 91644.0, 91725.08274917165, 92101.53949230479, 92974.74588389375, 6238.656873871981, 6997.265287144776, 7517.473776835569, 7977.92022079795, 8317.833643326978, 11014.39420831862, 12904.870951290366, 13035.608931732064, 13534.0, 13591.388387522387, 11108.666739997812, 11189.552417903518, 11315.773684526099, 11456.770864582828, 11734.061275097802, 11221.102311815199, 11237.4381280934, 11272.887271127183, 11453.427328633368, 11485.598004069818, 11280.943595281971, 11681.357525335849, 11806.0, 11911.166218364979, 11977.161598688357, 7492.1384040836, 7559.735409260649, 7583.2416758323725, 7609.619519024137, 7724.111727151225, 11293.209475336647, 11377.146713996317, 11553.537858485903, 11571.884281157418, 11634.709132271422], "value": 9851.931731144376, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1576.0779943732919, 1604.2620394618475, 1688.510332003719, 1843.6128413033261, 1967.3999430173792, 7064.47016473764, 8751.737447876565, 9143.222826059784, 9145.314101442393, 9151.130642588943, 7886.053673559167, 8089.797755056126, 8096.91903080969, 8222.095569487352, 8946.82106357874, 2137.818285445739, 2174.0760176924823, 2318.698569186012, 2391.9162829300976, 2398.712154541449, 2375.370526810388, 2391.270662447953, 2392.7726865947707, 2393.868337241451, 2397.9449042421365, 2350.27141586108, 2397.1609936522163, 2404.194594810732, 2405.7353691093967, 2406.0496104038893, 2119.63966125759, 2317.2237300504353, 2374.952500949971, 2378.8691621960957, 2389.7968672662846, 2363.1728894886824, 2370.8340416170877, 2387.77316154965, 2388.5103553771437, 2390.940226494334, 9096.63613455461, 9188.26493880488, 9194.95402522983, 9201.53992300389, 9214.90785092154, 5328.440513746078, 5418.8916221675345, 5473.8357849264585, 5487.396386397493, 5492.368377636543, 3051.893183738581, 3111.222194451387, 3576.1774791798034, 3658.3414985302634, 3662.2675464907124, 1006.8238058339762, 1037.906588407047, 1052.0741747262437, 1074.2802322443981, 1074.376861420382, 11930.343831089287, 11942.880571194264, 11982.820257696141, 11991.22057066288, 12050.517979280907, 4618.792154353062, 4709.293605959134, 4855.975720121447, 4857.587105096107, 4867.415910090741, 10247.436390998417, 10258.487075646188, 10262.589496420209, 10268.486575671186, 10273.537690803782, 5479.780808767684, 5480.232767412565, 5484.890302194013, 5486.533644640251, 5487.835364939058, 4919.77860996256, 4927.211646136601, 4933.432655244621, 4996.3504744383, 5002.974985125051, 3704.8700146455053, 3865.130345672201, 3994.9001274967777, 4065.654419374386, 4138.5654506276405, 1072.3244356055634, 1235.7590269897464, 1298.0718786068007, 1310.6068179546098, 1311.9737605248033, 870.8693695945659, 945.4469135555639, 954.9761255968517, 968.970930872075, 1027.1269420992246, 4828.734419606955, 6104.4200800923845, 6202.503799696014, 6221.2845522765465, 6226.782062627786, 957.4973139101888, 965.8744363232735, 994.472929347447, 1009.9444530550891, 1020.1634659579222, 4128.091819799699, 4148.0, 4163.167366526707, 4217.198732240864, 4248.978755106266, 9184.12750788674, 10629.787404251872, 11120.054795342325, 11139.05318047975, 11145.275557088764, 11067.169962252863, 11070.889291107147, 11089.390083545482, 11120.38837863909, 11133.66599002015, 7926.24700653437, 8291.626876790559, 8327.50034997902, 8351.958240208882, 8378.0, 6062.0, 6113.663748493876, 6247.781327653511, 6268.811935641938, 6408.1349017881985, 9912.603495860085, 9986.001399859955, 10311.535980880728, 10326.793464130826, 10334.5349459273, 10218.846717299395, 10372.429516376671, 10447.05976462122, 10478.266521343432, 10523.263371563917, 7667.501612395176, 7700.460967732205, 7743.690252389842, 7748.225177482207, 7827.843443130992, 8377.664893404195, 8499.447535910145, 8500.532470713923, 8510.191531804468, 8512.91487085146, 6402.647854368054, 7524.548527088392, 7556.093268807778, 7614.314711675976, 7913.841723165389, 7512.948187253898, 7516.962415187889, 7595.202503737028, 7682.308592226726, 7762.5730584818375, 7164.534305270141, 7195.748148814766, 7246.5289756165685, 7249.601271930094, 7252.963735181291, 4958.181899986462, 5074.898502030012, 5213.478652134908, 5263.394709608291, 5372.973135134299, 5208.5051920067535, 5282.445343238905, 5322.920156197738, 5411.242426060433, 5422.186671999074], "value": 4684.22802257142, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [272.39935941249547, 307.8599237347007, 317.8140787639231, 319.17493279871525, 325.9967400325996, 4424.358468022139, 4779.545943135406, 4959.429665588458, 5437.238786569878, 5468.2071099690575, 5383.084875571152, 5412.134058550623, 5419.268398766166, 5456.890862182754, 5472.781088756455, 3894.0, 3922.0, 4016.879493615196, 4108.178364327132, 4131.979340103296, 4019.1358857845557, 4069.8604390770633, 4137.462129923106, 4142.659397440437, 4175.830767385122, 3716.3310604091253, 3898.610138986093, 4056.7565946043183, 4074.5925407459313, 4117.958820411803, 3368.4947257911263, 3676.761010534307, 3870.0711829161082, 4076.8980775480554, 4104.343305071204, 3282.638909719929, 3554.928901421957, 3555.822208889545, 3584.426491761332, 3603.0, 4729.527047295277, 4902.975485122552, 5251.86870328241, 5254.973725131388, 5337.386200586943, 3962.5641179470235, 4533.483916426382, 4667.336141129197, 4673.065386922629, 4714.787834547454, 4788.419821458945, 5572.3870374258795, 5652.349979752299, 5653.971730141364, 5676.205331253628, 3903.082775547744, 3930.508686414198, 3932.7050471214775, 3948.763074215556, 3973.543042550086, 4948.158813001798, 5639.578071608966, 5883.911741323926, 5940.960331941893, 6021.1269365942135, 3329.883454079096, 3739.6447337502896, 3976.866593020975, 4009.0, 4012.8194231259663, 4030.8589199378466, 4042.7265411395315, 4065.4918135233097, 4124.876253712393, 4134.627883490441, 4457.122303595892, 4723.504032076583, 4728.479867214673, 4740.288956656529, 4765.0, 4468.4414448193975, 5044.394672639307, 5495.34055913285, 5774.82675519735, 5790.334111577138, 1278.0606254403006, 1311.4623004568082, 1388.8263967004125, 1394.874461298488, 1402.4810819996462, 749.583980890611, 867.3971589745066, 875.3499653153851, 876.5923845411786, 896.2784958108655, 5198.766055527511, 5210.925553923204, 5303.0, 5311.814086507031, 5322.0, 4799.28010798383, 5392.946070539323, 5441.0, 5790.594658373958, 5834.0, 4941.061198372297, 5280.392754833243, 5297.417284098746, 5309.230161626542, 5315.255864179018, 2738.8493632850386, 2896.6668833084045, 2913.0969399485803, 3002.264445210937, 3043.52825312077, 5513.751881165357, 5521.420250873601, 5791.681457519877, 6008.639481631116, 6032.879342413127, 5129.102407078746, 5169.147090729987, 5503.807366742145, 5953.732082056231, 5997.570983751588, 4797.496262892486, 6052.001419765777, 6081.263431455951, 6109.297430795426, 6173.197484327009, 5730.856728581819, 5903.7048147593305, 5910.468057874812, 5925.792597259076, 5944.494717949023, 4845.491223421631, 5856.502197313276, 5870.559708022, 5926.822195334147, 5934.10108765191, 6081.5742897996715, 6098.573099882966, 6121.051237058263, 6166.630002199882, 6186.133941248138, 3309.238875058798, 3843.327417701835, 3876.321643712283, 3931.567527572022, 3940.408938659224, 5177.404598471224, 5622.8754249149515, 5679.778847547797, 5838.795642152504, 5897.498712609305, 5495.890082198413, 5682.886342273214, 5704.605759573047, 5731.799387021434, 5800.607854115066, 5306.893862122659, 5701.771929122789, 5717.285339332583, 5732.656040637575, 5741.224934633771, 5375.59683023767, 5581.9162712560155, 5599.300087489064, 5613.663180209201, 5646.971765141148, 5165.096108181128, 5439.347278326626, 5647.011772939802, 5691.971540142273, 5711.257536520226, 5571.721413929368, 5592.552595792408, 5596.216529685766, 5611.270534830446, 5625.803096891589], "value": 4154.874334368665, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [743.7322563877003, 749.8837680159576, 764.8929149919009, 800.3997002248316, 840.4325932136563, 15196.924015379935, 16572.171391430442, 16597.004179749223, 16658.83341166588, 16676.249568769403, 14904.478343257988, 15120.924395378008, 15157.924210378933, 15164.165970871625, 15188.620284492894, 708.1997343002425, 735.6799792090432, 842.3892677808601, 883.6862913665652, 909.5543183839903, 857.4083882121347, 915.1626261970289, 916.1617120334886, 916.7295647783919, 918.6830543462495, 951.3103000324778, 967.2310513142033, 969.3844408800427, 970.5292932927524, 972.0134063925121, 740.8888666699984, 930.6603089872148, 962.465831463541, 964.0118878149917, 968.2060710217625, 717.8097804081923, 718.3283629806148, 719.3094629156017, 719.6257945868172, 719.910011248594, 15339.539813805604, 15342.386304547805, 15359.539213823604, 15360.078395296317, 15360.846391536053, 13588.0, 13698.931505342509, 13753.0, 13909.860901390957, 13958.720825583532, 13003.804942925864, 13017.0, 13111.278879661615, 13113.737725245443, 13280.53518126861, 4578.51925547816, 4652.092841895832, 4657.650676199299, 4811.0, 4827.106985207748, 108972.0, 110039.44980275127, 110225.44887275592, 111328.88671113267, 111329.0, 11276.32342059479, 11348.546058157746, 11367.261128026652, 11382.772344553063, 11768.0, 23210.53578928412, 23215.303540893463, 23223.419414514774, 23237.3028809136, 23253.651195232083, 7719.0, 8108.027036755627, 8180.918190818015, 8277.58612069406, 8384.455010424303, 4509.571590698878, 4528.615067719281, 4554.225781617133, 4700.788464519105, 4721.834735784231, 3539.3806083935724, 3649.6532829381167, 3649.817509124533, 3650.178709790329, 3658.2500587379523, 516.4706176169382, 519.3949049357457, 528.3527678593692, 531.8431062836472, 539.4228175851879, 567.3900556901348, 571.9227904232959, 633.4520639646672, 641.6214433484266, 674.3054653706592, 8797.780055498535, 9393.5303234838, 9592.76018099553, 9783.951080244695, 9786.192426815922, 588.3028611095865, 636.8280564247627, 661.5710066256862, 683.1100785922574, 688.7727050073464, 4066.674666026711, 4706.623470122382, 4715.080559290974, 4761.455170666078, 4926.6551341406475, 142226.0, 145423.27288363702, 147645.52354476534, 147969.0, 148826.0, 88167.18328167261, 111810.32284515738, 116923.41538292254, 118485.99999999828, 118558.0, 72421.27578724145, 93019.0, 100885.49557252314, 102319.48840255752, 105454.99999999847, 8651.221390074923, 8661.480311181349, 8708.17272359125, 8773.605187766694, 9007.684731034382, 59846.80306393935, 59905.70047149737, 60962.695186523786, 61448.0, 62043.689781550805, 57918.710406447695, 58057.70971145117, 85069.1493085086, 85240.14759852322, 85339.5733021331, 11519.19365644397, 13734.725305494034, 13739.381727822072, 13944.651383715489, 14249.358778854768, 14980.700385992437, 15087.773683394538, 15399.230038498254, 15516.301766420764, 15720.60698482547, 14204.502842400467, 14482.782758258845, 14503.459604758655, 14587.927060365055, 14619.561413157622, 14345.0, 14485.348159333065, 14604.780928285873, 14624.926875365554, 14713.779293310823, 13938.094023888414, 13991.720165596835, 14108.929455352658, 14156.079854809404, 14285.42858285657, 9598.376105553116, 11419.200655954335, 11516.82724759146, 11882.881171188177, 11960.940195298968, 9416.905830941603, 11498.655040348804, 11510.942445288056, 11655.125865560287, 11701.941490292495], "value": 7900.564216964552, "unit": "score"}], "name": "dromaeo_css", "value": 7497.820780388731, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 113.0, 112.0, 115.0, 113.0, 114.0, 113.0, 113.0, 117.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 108.0, 106.0, 154.0, 105.0, 104.0, 125.0, 104.0, 138.0, 105.0], "value": 119.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 186.0, 184.0, 185.0, 182.0, 183.0, 183.0, 183.0, 185.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 76.0, 57.0, 106.0, 57.0, 57.0, 60.0, 59.0, 128.0, 59.0], "value": 73.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 92.0, 70.0, 74.0, 68.0, 95.0, 69.0, 67.0, 98.0], "value": 79.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 83.0, 86.0, 84.0, 87.0, 84.0, 86.0, 84.0, 87.0, 83.0], "value": 85.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 106.0, 147.0, 120.0, 94.0, 93.0, 93.0, 92.0], "value": 102.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 119.0, 137.0, 93.0, 87.0, 86.0, 93.0, 86.0, 86.0, 87.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 57.0, 57.0, 53.0, 54.0, 52.0, 54.0, 54.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 81.0, 77.0, 73.0, 71.0, 67.0, 69.0, 72.0, 70.0, 70.0], "value": 71.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 84.0, 81.0, 82.0, 82.0, 84.0, 83.0, 83.0, 85.0, 82.0], "value": 82.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 136.0, 135.0, 134.0, 134.0, 136.0, 126.0, 126.0, 228.0, 152.0], "value": 144.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 163.0, 163.0, 162.0, 160.0, 158.0, 160.0, 160.0, 160.0], "value": 161.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 65.0, 66.0, 66.0, 67.0, 65.0, 64.0, 64.0], "value": 65.7, "unit": "score"}], "name": "kraken", "value": 1432.3000000000002, "alertThreshold": 2.0}]} 14:41:32 INFO - Return code: 0 14:41:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:41:32 INFO - # TBPL SUCCESS # 14:41:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:41:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:41:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:41:32 INFO - 2016-07-13 14:41:32.890 screenresolution[1673:9635] starting screenresolution argv=screenresolution get 14:41:32 INFO - 2016-07-13 14:41:32.893 screenresolution[1673:9635] Display 0: 1600x1200x32@60 14:41:32 INFO - 2016-07-13 14:41:32.899 screenresolution[1674:9638] starting screenresolution argv=screenresolution list 14:41:32 INFO - Available Modes on Display 0 14:41:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:41:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:41:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:41:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:41:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:41:33 INFO - Intel Iris: 14:41:33 INFO - Chipset Model: Intel Iris 14:41:33 INFO - Type: GPU 14:41:33 INFO - Bus: Built-In 14:41:33 INFO - VRAM (Dynamic, Max): 1536 MB 14:41:33 INFO - Vendor: Intel (0x8086) 14:41:33 INFO - Device ID: 0x0a2e 14:41:33 INFO - Revision ID: 0x0009 14:41:33 INFO - Displays: 14:41:33 INFO - Display: 14:41:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:41:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:41:33 INFO - Display Serial Number: 1600x1200 60 14:41:33 INFO - Main Display: Yes 14:41:33 INFO - Mirror: Off 14:41:33 INFO - Online: Yes 14:41:33 INFO - Rotation: Supported 14:41:33 INFO - Return code: 0 14:41:33 INFO - Running post-action listener: _resource_record_post_action 14:41:33 INFO - ##### 14:41:33 INFO - ##### Finished run-tests step (success) 14:41:33 INFO - ##### 14:41:33 INFO - Running post-run listener: _resource_record_post_run 14:41:33 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 36873728; Write bytes: 705879040; Read time: 241; Write time: 1959 14:41:33 INFO - TinderboxPrint: CPU usage
53.5% 14:41:33 INFO - TinderboxPrint: I/O read bytes / time
36,873,728 / 241 14:41:33 INFO - TinderboxPrint: I/O write bytes / time
705,879,040 / 1,959 14:41:33 INFO - TinderboxPrint: CPU idle
2,253.4 (46.5%) 14:41:33 INFO - TinderboxPrint: CPU system
965.3 (19.9%) 14:41:33 INFO - TinderboxPrint: CPU user
1,623.0 (33.5%) 14:41:33 INFO - TinderboxPrint: Swap in / out
443,793,408 / 0 14:41:33 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 390556160; Write bytes: 384509440; Read time: 29199; Write time: 788 14:41:33 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 35919360; Write bytes: 296207872; Read time: 229; Write time: 1116 14:41:33 INFO - Running post-run listener: _upload_blobber_files 14:41:33 INFO - Blob upload gear active. 14:41:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:41:33 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 14:41:33 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'] 14:41:33 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 14:41:34 INFO - (blobuploader) - INFO - Open directory for files ... 14:41:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:41:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:41:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:41:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:41:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:41:34 INFO - (blobuploader) - INFO - Done attempting. 14:41:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:41:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:41:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:41:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:41:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:41:35 INFO - (blobuploader) - INFO - Done attempting. 14:41:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:41:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:41:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:41:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:41:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:41:37 INFO - (blobuploader) - INFO - Done attempting. 14:41:37 INFO - (blobuploader) - INFO - Iteration through files over. 14:41:37 INFO - Return code: 0 14:41:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:41:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:41:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6e5b8095e6d62e03fd16a44e56ea08454aa64c2c378f3d2dbf4fafe8083ebfe6aaff23413d69e56299a13d17ed3277ac2f31d5d5fe318f9715b0d68bbb3eaa6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2baab1e29e8cbd04e23a4bf7d391a2dda92140a6e4a09756a54c7c56da2dd909586412879d6d1b4801ea533515647dcfa93b3394f84bee676c8f9e37bb2ecef9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/23f81c60fa52a1695df61a7ac1d13dc6830c433dea9c2552a87ba2fcc2b81d826a1218c35c4cd95a3a40e30adda98f707609a673f8eef9a1594a736e1e88f8f1"} 14:41:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:41:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:41:37 INFO - Contents: 14:41:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6e5b8095e6d62e03fd16a44e56ea08454aa64c2c378f3d2dbf4fafe8083ebfe6aaff23413d69e56299a13d17ed3277ac2f31d5d5fe318f9715b0d68bbb3eaa6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2baab1e29e8cbd04e23a4bf7d391a2dda92140a6e4a09756a54c7c56da2dd909586412879d6d1b4801ea533515647dcfa93b3394f84bee676c8f9e37bb2ecef9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/23f81c60fa52a1695df61a7ac1d13dc6830c433dea9c2552a87ba2fcc2b81d826a1218c35c4cd95a3a40e30adda98f707609a673f8eef9a1594a736e1e88f8f1"} 14:41:37 INFO - Running post-run listener: copy_logs_to_upload_dir 14:41:37 INFO - Copying logs to upload dir... 14:41:37 INFO - mkdir: /builds/slave/test/build/upload/logs 14:41:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.269479 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-07-13 14:41:37.956692) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 14:41:37.960213) ========= 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.AC1ZWLbL6W/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.XbE1jDHOCp/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6e5b8095e6d62e03fd16a44e56ea08454aa64c2c378f3d2dbf4fafe8083ebfe6aaff23413d69e56299a13d17ed3277ac2f31d5d5fe318f9715b0d68bbb3eaa6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2baab1e29e8cbd04e23a4bf7d391a2dda92140a6e4a09756a54c7c56da2dd909586412879d6d1b4801ea533515647dcfa93b3394f84bee676c8f9e37bb2ecef9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/23f81c60fa52a1695df61a7ac1d13dc6830c433dea9c2552a87ba2fcc2b81d826a1218c35c4cd95a3a40e30adda98f707609a673f8eef9a1594a736e1e88f8f1"} build_url:https://queue.taskcluster.net/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011043 build_url: 'https://queue.taskcluster.net/v1/task/dfiRXS4YQKG3_RZTeyn58Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6e5b8095e6d62e03fd16a44e56ea08454aa64c2c378f3d2dbf4fafe8083ebfe6aaff23413d69e56299a13d17ed3277ac2f31d5d5fe318f9715b0d68bbb3eaa6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2baab1e29e8cbd04e23a4bf7d391a2dda92140a6e4a09756a54c7c56da2dd909586412879d6d1b4801ea533515647dcfa93b3394f84bee676c8f9e37bb2ecef9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/23f81c60fa52a1695df61a7ac1d13dc6830c433dea9c2552a87ba2fcc2b81d826a1218c35c4cd95a3a40e30adda98f707609a673f8eef9a1594a736e1e88f8f1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 14:41:37.993363) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 14:41:37.993711) ========= 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.AC1ZWLbL6W/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.XbE1jDHOCp/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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.005051 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 14:41:38.042878) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 14:41:38.043209) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 14:41:38.043590) ========= ========= Total master_lag: 0.17 =========