builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0012 starttime: 1468341828.29 results: success (0) buildid: 20160712072926 builduid: 2a755032168f4c49823b29fbd3de34fb revision: 965a291ce94981239d55bb1cf45e1279faa788a9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 09:43:48.289980) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 09:43:48.290464) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 09:43:48.290756) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 09:43:48.315619) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 09:43:48.316016) ========= 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.f4zkqW1iJz/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.7iiU39Qqg1/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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-12 09:43:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.42M=0.001s 2016-07-12 09:43:48 (9.42 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.078458 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 09:43:48.417677) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 09:43:48.417998) ========= 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.f4zkqW1iJz/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.7iiU39Qqg1/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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.028025 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 09:43:48.467185) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 09:43:48.467558) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 965a291ce94981239d55bb1cf45e1279faa788a9 --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 965a291ce94981239d55bb1cf45e1279faa788a9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.f4zkqW1iJz/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.7iiU39Qqg1/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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-12 09:43:48,542 truncating revision to first 12 chars 2016-07-12 09:43:48,542 Setting DEBUG logging. 2016-07-12 09:43:48,542 attempt 1/10 2016-07-12 09:43:48,542 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/965a291ce949?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-12 09:43:49,184 unpacking tar archive at: mozilla-central-965a291ce949/testing/mozharness/ program finished with exit code 0 elapsedTime=0.907930 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 09:43:49.397211) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 09:43:49.397533) ========= script_repo_revision: 965a291ce94981239d55bb1cf45e1279faa788a9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 09:43:49.397912) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 09:43:49.398191) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 09:43:49.416431) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-07-12 09:43:49.416824) ========= /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.f4zkqW1iJz/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.7iiU39Qqg1/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 09:43:49 INFO - MultiFileLogger online at 20160712 09:43:49 in /builds/slave/test 09:43:49 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 09:43:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:43:49 INFO - {'append_to_log': False, 09:43:49 INFO - 'base_work_dir': '/builds/slave/test', 09:43:49 INFO - 'blob_upload_branch': 'Firefox', 09:43:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:43:49 INFO - 'branch': 'Firefox', 09:43:49 INFO - 'buildbot_json_path': 'buildprops.json', 09:43:49 INFO - 'config_files': ('talos/mac_config.py',), 09:43:49 INFO - 'default_actions': ('clobber', 09:43:49 INFO - 'read-buildbot-config', 09:43:49 INFO - 'download-and-extract', 09:43:49 INFO - 'populate-webroot', 09:43:49 INFO - 'create-virtualenv', 09:43:49 INFO - 'install', 09:43:49 INFO - 'run-tests'), 09:43:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:43:49 INFO - 'download_minidump_stackwalk': True, 09:43:49 INFO - 'download_symbols': 'ondemand', 09:43:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:43:49 INFO - 'tooltool.py': '/tools/tooltool.py', 09:43:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:43:49 INFO - '/tools/misc-python/virtualenv.py')}, 09:43:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:43:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:43:49 INFO - 'installer_path': 'installer.exe', 09:43:49 INFO - 'log_level': 'info', 09:43:49 INFO - 'log_name': 'talos', 09:43:49 INFO - 'log_to_console': True, 09:43:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:43:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:43:49 INFO - 'opt_config_files': (), 09:43:49 INFO - 'pip_index': False, 09:43:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:43:49 INFO - 'cmd': ('bash', 09:43:49 INFO - '-c', 09:43:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:43:49 INFO - 'enabled': True, 09:43:49 INFO - 'halt_on_failure': False, 09:43:49 INFO - 'name': 'check_screen_resolution'},), 09:43:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:43:49 INFO - 'cmd': ('bash', 09:43:49 INFO - '-c', 09:43:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:43:49 INFO - 'enabled': True, 09:43:49 INFO - 'halt_on_failure': False, 09:43:49 INFO - 'name': 'check_screen_resolution'},), 09:43:49 INFO - 'run_cmd_checks_enabled': True, 09:43:49 INFO - 'sps_profile': False, 09:43:49 INFO - 'sps_profile_interval': 0, 09:43:49 INFO - 'suite': 'dromaeojs', 09:43:49 INFO - 'system_bits': '32', 09:43:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:43:49 INFO - 'title': 't-yosemite-r7-0012', 09:43:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:43:49 INFO - 'use_talos_json': True, 09:43:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:43:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:43:49 INFO - 'work_dir': 'build'} 09:43:49 INFO - ##### 09:43:49 INFO - ##### Running clobber step. 09:43:49 INFO - ##### 09:43:49 INFO - Running pre-action listener: _resource_record_pre_action 09:43:49 INFO - Running main action method: clobber 09:43:49 INFO - rmtree: /builds/slave/test/build 09:43:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:43:51 INFO - Running post-action listener: _resource_record_post_action 09:43:51 INFO - ##### 09:43:51 INFO - ##### Finished clobber step (success) 09:43:51 INFO - ##### 09:43:51 INFO - ##### 09:43:51 INFO - ##### Running read-buildbot-config step. 09:43:51 INFO - ##### 09:43:51 INFO - Running pre-action listener: _resource_record_pre_action 09:43:51 INFO - Running main action method: read_buildbot_config 09:43:51 INFO - Using buildbot properties: 09:43:51 INFO - { 09:43:51 INFO - "project": "", 09:43:51 INFO - "product": "firefox", 09:43:51 INFO - "script_repo_revision": "production", 09:43:51 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:43:51 INFO - "repository": "", 09:43:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:43:51 INFO - "buildid": "20160712072926", 09:43:51 INFO - "pgo_build": "False", 09:43:51 INFO - "basedir": "/builds/slave/test", 09:43:51 INFO - "buildnumber": 292, 09:43:51 INFO - "slavename": "t-yosemite-r7-0012", 09:43:51 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:43:51 INFO - "platform": "yosemite_r7", 09:43:51 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:43:51 INFO - "branch": "mozilla-central", 09:43:51 INFO - "repo_path": "mozilla-central", 09:43:51 INFO - "revision": "965a291ce94981239d55bb1cf45e1279faa788a9", 09:43:51 INFO - "stage_platform": "macosx64", 09:43:51 INFO - "builduid": "2a755032168f4c49823b29fbd3de34fb", 09:43:51 INFO - "slavebuilddir": "test" 09:43:51 INFO - } 09:43:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/RVcsBBmuTFaRFZDI31It6w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 09:43:51 INFO - Running post-action listener: _resource_record_post_action 09:43:51 INFO - ##### 09:43:51 INFO - ##### Finished read-buildbot-config step (success) 09:43:51 INFO - ##### 09:43:51 INFO - ##### 09:43:51 INFO - ##### Running download-and-extract step. 09:43:51 INFO - ##### 09:43:51 INFO - Running pre-action listener: _resource_record_pre_action 09:43:51 INFO - Running main action method: download_and_extract 09:43:51 INFO - mkdir: /builds/slave/test/build/tests 09:43:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:43:51 INFO - https://queue.taskcluster.net/v1/task/RVcsBBmuTFaRFZDI31It6w/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:43:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVcsBBmuTFaRFZDI31It6w/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:43:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVcsBBmuTFaRFZDI31It6w/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:43:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVcsBBmuTFaRFZDI31It6w/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 09:43:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVcsBBmuTFaRFZDI31It6w/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 09:43:52 INFO - Downloaded 1199 bytes. 09:43:52 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:43:52 INFO - Using the following test package requirements: 09:43:52 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:43:52 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:43:52 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 09:43:52 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:43:52 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 09:43:52 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:43:52 INFO - u'jsshell-mac.zip'], 09:43:52 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:43:52 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 09:43:52 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:43:52 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:43:52 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 09:43:52 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:43:52 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 09:43:52 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:43:52 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 09:43:52 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:43:52 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 09:43:52 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:43:52 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')]} 09:43:52 INFO - https://queue.taskcluster.net/v1/task/RVcsBBmuTFaRFZDI31It6w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:43:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVcsBBmuTFaRFZDI31It6w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:43:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVcsBBmuTFaRFZDI31It6w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:43:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVcsBBmuTFaRFZDI31It6w/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 09:43:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVcsBBmuTFaRFZDI31It6w/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 09:43:54 INFO - Downloaded 18772456 bytes. 09:43:54 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'] 09:43:54 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 09:43:57 INFO - Return code: 0 09:43:57 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 09:43: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')]} 09:43:57 INFO - https://queue.taskcluster.net/v1/task/RVcsBBmuTFaRFZDI31It6w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:43:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVcsBBmuTFaRFZDI31It6w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:43:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVcsBBmuTFaRFZDI31It6w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:43:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVcsBBmuTFaRFZDI31It6w/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 09:43:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVcsBBmuTFaRFZDI31It6w/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 09:43:58 INFO - Downloaded 18772456 bytes. 09:43:58 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'] 09:43:58 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 09:44:02 INFO - Return code: 0 09:44:02 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')]} 09:44:02 INFO - https://queue.taskcluster.net/v1/task/RVcsBBmuTFaRFZDI31It6w/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:44:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVcsBBmuTFaRFZDI31It6w/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:44:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVcsBBmuTFaRFZDI31It6w/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:44:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVcsBBmuTFaRFZDI31It6w/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 09:44:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVcsBBmuTFaRFZDI31It6w/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 09:44:03 INFO - Downloaded 14403317 bytes. 09:44:03 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'] 09:44:03 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 09:44:03 INFO - Return code: 0 09:44: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')]} 09:44:03 INFO - https://queue.taskcluster.net/v1/task/RVcsBBmuTFaRFZDI31It6w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:44:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVcsBBmuTFaRFZDI31It6w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:44:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVcsBBmuTFaRFZDI31It6w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:44:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVcsBBmuTFaRFZDI31It6w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 09:44:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVcsBBmuTFaRFZDI31It6w/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 09:44:06 INFO - Downloaded 112444458 bytes. 09:44:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RVcsBBmuTFaRFZDI31It6w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:44:06 INFO - mkdir: /builds/slave/test/properties 09:44:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:44:06 INFO - Writing to file /builds/slave/test/properties/build_url 09:44:06 INFO - Contents: 09:44:06 INFO - build_url:https://queue.taskcluster.net/v1/task/RVcsBBmuTFaRFZDI31It6w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:44:07 INFO - Running post-action listener: _resource_record_post_action 09:44:07 INFO - Running post-action listener: set_extra_try_arguments 09:44:07 INFO - ##### 09:44:07 INFO - ##### Finished download-and-extract step (success) 09:44:07 INFO - ##### 09:44:07 INFO - ##### 09:44:07 INFO - ##### Running populate-webroot step. 09:44:07 INFO - ##### 09:44:07 INFO - Running pre-action listener: _resource_record_pre_action 09:44:07 INFO - Running main action method: populate_webroot 09:44:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:44:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:44:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:44:07 INFO - '--tppagecycles', 09:44:07 INFO - '7'], 09:44:07 INFO - 'tests': ['tsvgm']}}, 09:44:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:44:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:44:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:44:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:44:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:44:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:44:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:44:07 INFO - 'talos_options': ['--disable-e10s'], 09:44:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:44:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:44:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:44:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:44:07 INFO - 'talos_options': ['--disable-e10s'], 09:44:07 INFO - 'tests': ['damp', 'tps']}, 09:44:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:44:07 INFO - 'tests': ['damp', 'tps']}, 09:44:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:44:07 INFO - 'tests': ['dromaeo_dom']}, 09:44:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:44:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:44:07 INFO - 'tests': ['basic_compositor_video']}, 09:44:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:44:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:44:07 INFO - 'tests': ['a11yr', 09:44:07 INFO - 'ts_paint', 09:44:07 INFO - 'tpaint', 09:44:07 INFO - 'sessionrestore', 09:44:07 INFO - 'sessionrestore_no_auto_restore', 09:44:07 INFO - 'tabpaint']}, 09:44:07 INFO - 'other-e10s': {'tests': ['a11yr', 09:44:07 INFO - 'ts_paint', 09:44:07 INFO - 'tpaint', 09:44:07 INFO - 'sessionrestore', 09:44:07 INFO - 'sessionrestore_no_auto_restore', 09:44:07 INFO - 'tabpaint']}, 09:44:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:44:07 INFO - 'ts_paint', 09:44:07 INFO - 'tpaint', 09:44:07 INFO - 'sessionrestore', 09:44:07 INFO - 'sessionrestore_no_auto_restore', 09:44:07 INFO - 'tabpaint']}, 09:44:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:44:07 INFO - 'ts_paint', 09:44:07 INFO - 'tpaint', 09:44:07 INFO - 'sessionrestore', 09:44:07 INFO - 'sessionrestore_no_auto_restore', 09:44:07 INFO - 'tabpaint']}, 09:44:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:44:07 INFO - 'tests': ['a11yr', 09:44:07 INFO - 'ts_paint', 09:44:07 INFO - 'tpaint', 09:44:07 INFO - 'sessionrestore', 09:44:07 INFO - 'sessionrestore_no_auto_restore', 09:44:07 INFO - 'tabpaint']}, 09:44:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:44:07 INFO - 'tests': ['a11yr', 09:44:07 INFO - 'ts_paint', 09:44:07 INFO - 'tpaint', 09:44:07 INFO - 'sessionrestore', 09:44:07 INFO - 'sessionrestore_no_auto_restore', 09:44:07 INFO - 'tabpaint']}, 09:44:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:44:07 INFO - 'tests': ['tsvgx', 09:44:07 INFO - 'tsvgr_opacity', 09:44:07 INFO - 'tart', 09:44:07 INFO - 'tscrollx', 09:44:07 INFO - 'cart']}, 09:44:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:44:07 INFO - 'tsvgr_opacity', 09:44:07 INFO - 'tart', 09:44:07 INFO - 'tscrollx', 09:44:07 INFO - 'cart']}, 09:44:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:44:07 INFO - 'talos_options': ['--disable-e10s'], 09:44:07 INFO - 'tests': ['tp5o']}, 09:44:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:44:07 INFO - 'tests': ['tp5o']}, 09:44:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:44:07 INFO - 'talos_options': ['--disable-e10s', 09:44:07 INFO - '--xperf_path', 09:44:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:44:07 INFO - 'tests': ['tp5n']}, 09:44:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:44:07 INFO - 'talos_options': ['--xperf_path', 09:44:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:44:07 INFO - 'tests': ['tp5n']}}, 09:44:07 INFO - 'talos.zip': {'path': '', 09:44:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:44:07 INFO - Running post-action listener: _resource_record_post_action 09:44:07 INFO - ##### 09:44:07 INFO - ##### Finished populate-webroot step (success) 09:44:07 INFO - ##### 09:44:07 INFO - ##### 09:44:07 INFO - ##### Running create-virtualenv step. 09:44:07 INFO - ##### 09:44:07 INFO - Running pre-action listener: _resource_record_pre_action 09:44:07 INFO - Running main action method: create_virtualenv 09:44:07 INFO - Creating virtualenv /builds/slave/test/build/venv 09:44:07 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 09:44:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:44:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:44:07 INFO - Using real prefix '/tools/python27' 09:44:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:44:08 INFO - Installing distribute.............................................................................................................................................................................................done. 09:44:10 INFO - Installing pip.................done. 09:44:10 INFO - Return code: 0 09:44:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:44:10 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')]} 09:44:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:10 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')]} 09:44:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:10 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 0x102b28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bbd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff438569640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a8cd30>, '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 0x1027f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a84b20>, '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.f4zkqW1iJz/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.7iiU39Qqg1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 09:44:10 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 09:44:10 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 09:44:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f4zkqW1iJz/Render', 09:44:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:10 INFO - 'HOME': '/Users/cltbld', 09:44:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:10 INFO - 'LOGNAME': 'cltbld', 09:44:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:10 INFO - 'MOZ_NO_REMOTE': '1', 09:44:10 INFO - 'NO_EM_RESTART': '1', 09:44:10 INFO - 'PAGER': '/bin/cat', 09:44:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:10 INFO - 'PWD': '/builds/slave/test', 09:44:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:10 INFO - 'SHELL': '/bin/bash', 09:44:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7iiU39Qqg1/Listeners', 09:44:10 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 09:44:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:10 INFO - 'USER': 'cltbld', 09:44:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:10 INFO - 'XPC_FLAGS': '0x0', 09:44:10 INFO - 'XPC_SERVICE_NAME': '0', 09:44:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:11 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 09:44:11 INFO - Cleaning up... 09:44:11 INFO - Return code: 0 09:44:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:44: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')]} 09:44:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44: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')]} 09:44:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:11 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 0x102b28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bbd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff438569640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a8cd30>, '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 0x1027f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a84b20>, '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.f4zkqW1iJz/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.7iiU39Qqg1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 09:44:11 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 09:44:11 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 09:44:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f4zkqW1iJz/Render', 09:44:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:11 INFO - 'HOME': '/Users/cltbld', 09:44:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:11 INFO - 'LOGNAME': 'cltbld', 09:44:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:11 INFO - 'MOZ_NO_REMOTE': '1', 09:44:11 INFO - 'NO_EM_RESTART': '1', 09:44:11 INFO - 'PAGER': '/bin/cat', 09:44:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:11 INFO - 'PWD': '/builds/slave/test', 09:44:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:11 INFO - 'SHELL': '/bin/bash', 09:44:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7iiU39Qqg1/Listeners', 09:44:11 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 09:44:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:11 INFO - 'USER': 'cltbld', 09:44:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:11 INFO - 'XPC_FLAGS': '0x0', 09:44:11 INFO - 'XPC_SERVICE_NAME': '0', 09:44:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:11 INFO - Downloading/unpacking psutil>=3.1.1 09:44:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:44:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:44:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:44:14 INFO - Installing collected packages: psutil 09:44:14 INFO - Running setup.py install for psutil 09:44:14 INFO - building 'psutil._psutil_osx' extension 09:44:14 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 09:44:14 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 09:44:14 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 09:44:14 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 09:44:15 INFO - building 'psutil._psutil_posix' extension 09:44:15 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 09:44:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:44:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:44:15 INFO - ^ 09:44:15 INFO - 1 warning generated. 09:44:15 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 09:44:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:44:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:44:15 INFO - Successfully installed psutil 09:44:15 INFO - Cleaning up... 09:44:15 INFO - Return code: 0 09:44:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:44:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:44:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:44:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102b28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bbd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff438569640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a8cd30>, '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 0x1027f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a84b20>, '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.f4zkqW1iJz/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.7iiU39Qqg1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 09:44:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:44:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:44:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f4zkqW1iJz/Render', 09:44:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:15 INFO - 'HOME': '/Users/cltbld', 09:44:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:15 INFO - 'LOGNAME': 'cltbld', 09:44:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:15 INFO - 'MOZ_NO_REMOTE': '1', 09:44:15 INFO - 'NO_EM_RESTART': '1', 09:44:15 INFO - 'PAGER': '/bin/cat', 09:44:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:15 INFO - 'PWD': '/builds/slave/test', 09:44:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:15 INFO - 'SHELL': '/bin/bash', 09:44:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7iiU39Qqg1/Listeners', 09:44:15 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 09:44:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:15 INFO - 'USER': 'cltbld', 09:44:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:15 INFO - 'XPC_FLAGS': '0x0', 09:44:15 INFO - 'XPC_SERVICE_NAME': '0', 09:44:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:44:15 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 09:44:15 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 09:44:15 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 09:44:15 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 09:44:15 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 09:44:15 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 09:44:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:44:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:44:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:44:18 INFO - Installing collected packages: mozsystemmonitor 09:44:18 INFO - Running setup.py install for mozsystemmonitor 09:44:18 INFO - Successfully installed mozsystemmonitor 09:44:18 INFO - Cleaning up... 09:44:18 INFO - Return code: 0 09:44:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:44:18 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')]} 09:44:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:18 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')]} 09:44:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:18 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 0x102b28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bbd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff438569640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a8cd30>, '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 0x1027f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a84b20>, '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.f4zkqW1iJz/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.7iiU39Qqg1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 09:44:18 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 09:44:18 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 09:44:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f4zkqW1iJz/Render', 09:44:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:18 INFO - 'HOME': '/Users/cltbld', 09:44:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:18 INFO - 'LOGNAME': 'cltbld', 09:44:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:18 INFO - 'MOZ_NO_REMOTE': '1', 09:44:18 INFO - 'NO_EM_RESTART': '1', 09:44:18 INFO - 'PAGER': '/bin/cat', 09:44:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:18 INFO - 'PWD': '/builds/slave/test', 09:44:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:18 INFO - 'SHELL': '/bin/bash', 09:44:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7iiU39Qqg1/Listeners', 09:44:18 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 09:44:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:18 INFO - 'USER': 'cltbld', 09:44:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:18 INFO - 'XPC_FLAGS': '0x0', 09:44:18 INFO - 'XPC_SERVICE_NAME': '0', 09:44:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:18 INFO - Downloading/unpacking jsonschema==2.5.1 09:44: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 09:44: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 09:44: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 09:44: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 09:44: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 09:44: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 09:44:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:44:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:44:21 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 09:44:21 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 09:44:21 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 09:44:21 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 09:44:21 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 09:44:21 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 09:44:21 INFO - Downloading functools32-3.2.3-2.tar.gz 09:44:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:44:21 INFO - warning: no files found matching '*.txt' 09:44:21 INFO - no previously-included directories found matching 'build' 09:44:21 INFO - no previously-included directories found matching 'dist' 09:44:21 INFO - no previously-included directories found matching '.git*' 09:44:21 INFO - Installing collected packages: jsonschema, functools32 09:44:21 INFO - Running setup.py install for functools32 09:44:21 INFO - warning: no files found matching '*.txt' 09:44:21 INFO - no previously-included directories found matching 'build' 09:44:21 INFO - no previously-included directories found matching 'dist' 09:44:21 INFO - no previously-included directories found matching '.git*' 09:44:21 INFO - Successfully installed jsonschema functools32 09:44:21 INFO - Cleaning up... 09:44:21 INFO - Return code: 0 09:44:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:44: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')]} 09:44:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44: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')]} 09:44:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44: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', '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 0x102b28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bbd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff438569640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a8cd30>, '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 0x1027f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a84b20>, '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.f4zkqW1iJz/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.7iiU39Qqg1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 09:44: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:44: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 blobuploader==1.2.4 09:44:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f4zkqW1iJz/Render', 09:44:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:21 INFO - 'HOME': '/Users/cltbld', 09:44:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:21 INFO - 'LOGNAME': 'cltbld', 09:44:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:21 INFO - 'MOZ_NO_REMOTE': '1', 09:44:21 INFO - 'NO_EM_RESTART': '1', 09:44:21 INFO - 'PAGER': '/bin/cat', 09:44:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:21 INFO - 'PWD': '/builds/slave/test', 09:44:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:21 INFO - 'SHELL': '/bin/bash', 09:44:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7iiU39Qqg1/Listeners', 09:44:21 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 09:44:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:21 INFO - 'USER': 'cltbld', 09:44:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:21 INFO - 'XPC_FLAGS': '0x0', 09:44:21 INFO - 'XPC_SERVICE_NAME': '0', 09:44:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:22 INFO - Downloading/unpacking blobuploader==1.2.4 09:44:22 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 09:44:22 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 09:44:22 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 09:44:22 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 09:44:22 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 09:44:22 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 09:44:24 INFO - Downloading blobuploader-1.2.4.tar.gz 09:44:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:44:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:44:24 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 09:44:24 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 09:44:24 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 09:44:24 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 09:44:24 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 09:44:24 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 09:44:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:44:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:44:25 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 09:44:25 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 09:44:25 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 09:44:25 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 09:44:25 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 09:44:25 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 09:44:25 INFO - Downloading docopt-0.6.1.tar.gz 09:44:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:44:25 INFO - Installing collected packages: blobuploader, requests, docopt 09:44:25 INFO - Running setup.py install for blobuploader 09:44:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:44:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:44:25 INFO - Running setup.py install for requests 09:44:26 INFO - Running setup.py install for docopt 09:44:26 INFO - Successfully installed blobuploader requests docopt 09:44:26 INFO - Cleaning up... 09:44:26 INFO - Return code: 0 09:44:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:44:26 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')]} 09:44:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:26 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')]} 09:44:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:26 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 0x102b28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bbd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff438569640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a8cd30>, '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 0x1027f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a84b20>, '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.f4zkqW1iJz/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.7iiU39Qqg1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 09:44:26 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 09:44:26 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 09:44:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f4zkqW1iJz/Render', 09:44:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:26 INFO - 'HOME': '/Users/cltbld', 09:44:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:26 INFO - 'LOGNAME': 'cltbld', 09:44:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:26 INFO - 'MOZ_NO_REMOTE': '1', 09:44:26 INFO - 'NO_EM_RESTART': '1', 09:44:26 INFO - 'PAGER': '/bin/cat', 09:44:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:26 INFO - 'PWD': '/builds/slave/test', 09:44:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:26 INFO - 'SHELL': '/bin/bash', 09:44:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7iiU39Qqg1/Listeners', 09:44:26 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 09:44:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:26 INFO - 'USER': 'cltbld', 09:44:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:26 INFO - 'XPC_FLAGS': '0x0', 09:44:26 INFO - 'XPC_SERVICE_NAME': '0', 09:44:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:44:26 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-lLhGHW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:44:27 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-654YHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:44:27 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-RGSnaT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:44:27 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-oU0EBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:44:27 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-flduQd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:44:27 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-hKjZWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:44:27 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-IXHx7F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:44:27 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-9JR1Mb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:44:27 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-FP5FdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:44:27 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-AzOQMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:44:27 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-hYefIw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:44:28 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-aQL1NR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:44:28 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-NIMoJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:44:28 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-Htn7G5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:44:28 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-6JfG3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:44:28 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-5QRmiL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:44:28 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-hal__U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:44:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:44:28 INFO - Running setup.py install for manifestparser 09:44:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:44:28 INFO - Running setup.py install for mozcrash 09:44:28 INFO - Running setup.py install for mozdebug 09:44:29 INFO - Running setup.py install for mozdevice 09:44:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:44:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:44:29 INFO - Running setup.py install for mozfile 09:44:29 INFO - Running setup.py install for mozhttpd 09:44:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:44:29 INFO - Running setup.py install for mozinfo 09:44:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:44:29 INFO - Running setup.py install for mozInstall 09:44:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:44:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:44:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:44:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:44:29 INFO - Running setup.py install for mozleak 09:44:29 INFO - Running setup.py install for mozlog 09:44:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:44:30 INFO - Running setup.py install for moznetwork 09:44:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:44:30 INFO - Running setup.py install for mozprocess 09:44:30 INFO - Running setup.py install for mozprofile 09:44:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:44:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:44:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:44:30 INFO - Running setup.py install for mozrunner 09:44:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:44:30 INFO - Running setup.py install for mozscreenshot 09:44:30 INFO - Running setup.py install for moztest 09:44:31 INFO - Running setup.py install for mozversion 09:44:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:44:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:44:31 INFO - Cleaning up... 09:44:31 INFO - Return code: 0 09:44:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:44: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')]} 09:44:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44: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')]} 09:44:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:31 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 0x102b28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bbd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff438569640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a8cd30>, '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 0x1027f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a84b20>, '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.f4zkqW1iJz/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.7iiU39Qqg1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 09:44:31 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 09:44:31 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 09:44:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f4zkqW1iJz/Render', 09:44:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:31 INFO - 'HOME': '/Users/cltbld', 09:44:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:31 INFO - 'LOGNAME': 'cltbld', 09:44:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:31 INFO - 'MOZ_NO_REMOTE': '1', 09:44:31 INFO - 'NO_EM_RESTART': '1', 09:44:31 INFO - 'PAGER': '/bin/cat', 09:44:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:31 INFO - 'PWD': '/builds/slave/test', 09:44:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:31 INFO - 'SHELL': '/bin/bash', 09:44:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7iiU39Qqg1/Listeners', 09:44:31 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 09:44:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:31 INFO - 'USER': 'cltbld', 09:44:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:31 INFO - 'XPC_FLAGS': '0x0', 09:44:31 INFO - 'XPC_SERVICE_NAME': '0', 09:44:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:44:31 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-AxMlrl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:44:31 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)) 09:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:44:31 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-IL9Rnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:44:31 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)) 09:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:44:31 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-tZRH_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:44:31 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)) 09:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:44:31 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-phsATG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:44:31 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)) 09:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:44:31 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-RHi_0E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:44:32 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)) 09:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:44:32 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-TtN2WF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:44:32 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)) 09:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:44:32 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-OhlRkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:44:32 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)) 09:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:44:32 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-N2c8E8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:44:32 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)) 09:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:44:32 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-b3fiM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:44:32 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)) 09:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:44:32 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-8bgC66-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:44:32 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)) 09:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:44:32 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-D3yX5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:44:32 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)) 09:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:44:32 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-VqfD_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:44:32 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)) 09:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:44:32 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-VDBnnl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:44:32 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)) 09:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:44:32 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-gY7q_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:44:33 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)) 09:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:44:33 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-7TZvKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:44:33 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)) 09:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:44:33 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-OqGHXM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:44:33 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)) 09:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:44:33 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-dLeqJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:44:33 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)) 09:44:33 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)) 09:44:33 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)) 09:44:33 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)) 09:44:33 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)) 09:44:33 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)) 09:44:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:44:33 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 09:44:33 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 09:44:33 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 09:44:33 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 09:44:33 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 09:44:33 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 09:44:35 INFO - Downloading blessings-1.6.tar.gz 09:44:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:44:35 INFO - Installing collected packages: blessings 09:44:35 INFO - Running setup.py install for blessings 09:44:36 INFO - Successfully installed blessings 09:44:36 INFO - Cleaning up... 09:44:36 INFO - Return code: 0 09:44:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:44:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:44:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:44:36 INFO - Reading from file tmpfile_stdout 09:44:36 INFO - Current package versions: 09:44:36 INFO - blessings == 1.6 09:44:36 INFO - blobuploader == 1.2.4 09:44:36 INFO - docopt == 0.6.1 09:44:36 INFO - functools32 == 3.2.3-2 09:44:36 INFO - jsonschema == 2.5.1 09:44:36 INFO - manifestparser == 1.1 09:44:36 INFO - mozInstall == 1.12 09:44:36 INFO - mozcrash == 0.17 09:44:36 INFO - mozdebug == 0.1 09:44:36 INFO - mozdevice == 0.48 09:44:36 INFO - mozfile == 1.2 09:44:36 INFO - mozhttpd == 0.7 09:44:36 INFO - mozinfo == 0.9 09:44:36 INFO - mozleak == 0.1 09:44:36 INFO - mozlog == 3.2 09:44:36 INFO - moznetwork == 0.27 09:44:36 INFO - mozprocess == 0.23 09:44:36 INFO - mozprofile == 0.28 09:44:36 INFO - mozrunner == 6.12 09:44:36 INFO - mozscreenshot == 0.1 09:44:36 INFO - mozsystemmonitor == 0.3 09:44:36 INFO - moztest == 0.7 09:44:36 INFO - mozversion == 1.4 09:44:36 INFO - psutil == 3.1.1 09:44:36 INFO - requests == 1.2.3 09:44:36 INFO - wsgiref == 0.1.2 09:44:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:44:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:44:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:36 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 0x102b28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bbd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff438569640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a8cd30>, '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 0x1027f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a84b20>, '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.f4zkqW1iJz/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.7iiU39Qqg1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 09:44:36 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 09:44:36 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 09:44:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f4zkqW1iJz/Render', 09:44:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:36 INFO - 'HOME': '/Users/cltbld', 09:44:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:36 INFO - 'LOGNAME': 'cltbld', 09:44:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:36 INFO - 'MOZ_NO_REMOTE': '1', 09:44:36 INFO - 'NO_EM_RESTART': '1', 09:44:36 INFO - 'PAGER': '/bin/cat', 09:44:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:36 INFO - 'PWD': '/builds/slave/test', 09:44:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:36 INFO - 'SHELL': '/bin/bash', 09:44:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7iiU39Qqg1/Listeners', 09:44:36 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 09:44:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:36 INFO - 'USER': 'cltbld', 09:44:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:36 INFO - 'XPC_FLAGS': '0x0', 09:44:36 INFO - 'XPC_SERVICE_NAME': '0', 09:44:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:36 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)) 09:44:36 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)) 09:44:36 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)) 09:44:36 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)) 09:44:36 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)) 09:44:36 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)) 09:44:36 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)) 09:44:36 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)) 09:44:36 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)) 09:44:36 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)) 09:44:36 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)) 09:44:36 INFO - Cleaning up... 09:44:36 INFO - Return code: 0 09:44:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:44:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:44:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102b28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bbd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff438569640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a8cd30>, '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 0x1027f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a84b20>, '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.f4zkqW1iJz/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.7iiU39Qqg1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 09:44:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:44:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:44:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f4zkqW1iJz/Render', 09:44:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:36 INFO - 'HOME': '/Users/cltbld', 09:44:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:36 INFO - 'LOGNAME': 'cltbld', 09:44:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:36 INFO - 'MOZ_NO_REMOTE': '1', 09:44:36 INFO - 'NO_EM_RESTART': '1', 09:44:36 INFO - 'PAGER': '/bin/cat', 09:44:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:36 INFO - 'PWD': '/builds/slave/test', 09:44:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:36 INFO - 'SHELL': '/bin/bash', 09:44:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7iiU39Qqg1/Listeners', 09:44:36 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 09:44:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:36 INFO - 'USER': 'cltbld', 09:44:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:36 INFO - 'XPC_FLAGS': '0x0', 09:44:36 INFO - 'XPC_SERVICE_NAME': '0', 09:44:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:44:36 INFO - Cleaning up... 09:44:36 INFO - Return code: 0 09:44:36 INFO - Running post-action listener: _resource_record_post_action 09:44:36 INFO - Running post-action listener: _start_resource_monitoring 09:44:36 INFO - Starting resource monitoring. 09:44:36 INFO - ##### 09:44:36 INFO - ##### Finished create-virtualenv step (success) 09:44:36 INFO - ##### 09:44:36 INFO - ##### 09:44:36 INFO - ##### Running install step. 09:44:36 INFO - ##### 09:44:36 INFO - Running pre-action listener: _resource_record_pre_action 09:44:36 INFO - Running main action method: install 09:44:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:44:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:44:37 INFO - Reading from file tmpfile_stdout 09:44:37 INFO - Detecting whether we're running mozinstall >=1.0... 09:44:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:44:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:44:37 INFO - Reading from file tmpfile_stdout 09:44:37 INFO - Output received: 09:44:37 INFO - Usage: mozinstall [options] installer 09:44:37 INFO - Options: 09:44:37 INFO - -h, --help show this help message and exit 09:44:37 INFO - -d DEST, --destination=DEST 09:44:37 INFO - Directory to install application into. [default: 09:44:37 INFO - "/builds/slave/test"] 09:44:37 INFO - --app=APP Application being installed. [default: firefox] 09:44:37 INFO - mkdir: /builds/slave/test/build/application 09:44:37 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'] 09:44:37 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 09:45:11 INFO - Reading from file tmpfile_stdout 09:45:11 INFO - Output received: 09:45:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:45:11 INFO - Running post-action listener: _resource_record_post_action 09:45:11 INFO - ##### 09:45:11 INFO - ##### Finished install step (success) 09:45:11 INFO - ##### 09:45:11 INFO - ##### 09:45:11 INFO - ##### Running run-tests step. 09:45:11 INFO - ##### 09:45:11 INFO - Running pre-action listener: _resource_record_pre_action 09:45:11 INFO - Running main action method: run_tests 09:45:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:45:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:45:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:45:11 INFO - 2016-07-12 09:45:11.314 screenresolution[1641:7403] starting screenresolution argv=screenresolution get 09:45:11 INFO - 2016-07-12 09:45:11.317 screenresolution[1641:7403] Display 0: 1600x1200x32@60 09:45:11 INFO - 2016-07-12 09:45:11.323 screenresolution[1642:7406] starting screenresolution argv=screenresolution list 09:45:11 INFO - Available Modes on Display 0 09:45:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:45:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:45:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:45:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:45:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:45:11 INFO - Intel Iris: 09:45:11 INFO - Chipset Model: Intel Iris 09:45:11 INFO - Type: GPU 09:45:11 INFO - Bus: Built-In 09:45:11 INFO - VRAM (Dynamic, Max): 1536 MB 09:45:11 INFO - Vendor: Intel (0x8086) 09:45:11 INFO - Device ID: 0x0a2e 09:45:11 INFO - Revision ID: 0x0009 09:45:11 INFO - Displays: 09:45:11 INFO - Display: 09:45:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:45:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:45:11 INFO - Display Serial Number: 1600x1200 60 09:45:11 INFO - Main Display: Yes 09:45:11 INFO - Mirror: Off 09:45:11 INFO - Online: Yes 09:45:11 INFO - Rotation: Supported 09:45:11 INFO - Return code: 0 09:45:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:45:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:45:11 INFO - Python 2.7.3 09:45:11 INFO - Return code: 0 09:45:11 INFO - grabbing minidump binary from tooltool 09:45: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')]} 09:45:11 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 0x102a8cd30>, '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 0x1027f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102a84b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:45:11 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 09:45:11 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 09:45:11 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 09:45:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:45:11 INFO - Return code: 0 09:45:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:45:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:45:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:45:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:45:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:45:11 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/RVcsBBmuTFaRFZDI31It6w/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0012', '--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 09:45:11 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/RVcsBBmuTFaRFZDI31It6w/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0012 --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 09:45:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f4zkqW1iJz/Render', 09:45:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:11 INFO - 'HOME': '/Users/cltbld', 09:45:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:11 INFO - 'LOGNAME': 'cltbld', 09:45:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:45:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:45:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:11 INFO - 'MOZ_NO_REMOTE': '1', 09:45:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:45:11 INFO - 'NO_EM_RESTART': '1', 09:45:11 INFO - 'PAGER': '/bin/cat', 09:45:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:11 INFO - 'PWD': '/builds/slave/test', 09:45:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:45:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:11 INFO - 'SHELL': '/bin/bash', 09:45:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7iiU39Qqg1/Listeners', 09:45:11 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 09:45:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:11 INFO - 'USER': 'cltbld', 09:45:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:11 INFO - 'XPC_FLAGS': '0x0', 09:45:11 INFO - 'XPC_SERVICE_NAME': '0', 09:45:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:11 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/RVcsBBmuTFaRFZDI31It6w/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0012', '--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 09:45:11 INFO - mozversion application_buildid: 20160712072926 09:45:11 INFO - mozversion application_changeset: 965a291ce94981239d55bb1cf45e1279faa788a9 09:45:11 INFO - mozversion application_display_name: Nightly 09:45:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:45:11 INFO - mozversion application_name: Firefox 09:45:11 INFO - mozversion application_remotingname: firefox 09:45:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:45:11 INFO - mozversion application_vendor: Mozilla 09:45:11 INFO - mozversion application_version: 50.0a1 09:45:11 INFO - mozversion platform_buildid: 20160712072926 09:45:11 INFO - mozversion platform_changeset: 965a291ce94981239d55bb1cf45e1279faa788a9 09:45:11 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:45:11 INFO - mozversion platform_version: 50.0a1 09:45:11 INFO - using testdate: 1468341911 09:45:11 INFO - actual date: 1468341911 09:45:11 INFO - starting webserver on 'localhost:49272' 09:45:11 INFO - SUITE-START | Running 2 tests 09:45:11 INFO - TEST-START | dromaeo_css 09:45:11 INFO - operating with platform_type : mac_ 09:45:11 INFO - Initialising browser for dromaeo_css test... 09:45:11 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmp6TyEFN/profile http://localhost:49272/getInfo.html) 09:45:14 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 09:45:14 INFO - PROCESS | 1652 | colorDepth:24 09:45:14 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 09:45:14 INFO - PROCESS | 1652 | __metrics 09:45:14 INFO - PROCESS | 1652 | 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" 09:45:14 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:45:15 INFO - TEST-INFO | 1652: exit 0 09:45:15 INFO - Browser initialized. 09:45:15 INFO - Running cycle 1/1 for dromaeo_css test... 09:45:15 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmp6TyEFN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:45:26 INFO - PROCESS | 1654 | RSS: Main: 243548160 09:45:26 INFO - PROCESS | 1654 | 09:46:18 INFO - PROCESS | 1654 | 1468341978514 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] 09:48:29 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 09:48:30 INFO - PROCESS | 1654 | RSS: Main: 372043776 09:48:30 INFO - PROCESS | 1654 | 09:51:33 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 09:51:34 INFO - PROCESS | 1654 | RSS: Main: 381820928 09:51:34 INFO - PROCESS | 1654 | 09:54:36 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 09:54:37 INFO - PROCESS | 1654 | RSS: Main: 373075968 09:54:37 INFO - PROCESS | 1654 | 09:57:39 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 09:57:39 INFO - PROCESS | 1654 | RSS: Main: 365690880 09:57:39 INFO - PROCESS | 1654 | 10:00:43 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 10:00:43 INFO - PROCESS | 1654 | RSS: Main: 340688896 10:00:43 INFO - PROCESS | 1654 | 10:03:46 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 10:03:47 INFO - PROCESS | 1654 | RSS: Main: 415191040 10:03:47 INFO - PROCESS | 1654 | 10:03:47 INFO - PROCESS | 1654 | __start_tp_report 10:03:47 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 10:03:47 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 10:03:47 INFO - PROCESS | 1654 | |i|pagename|runs| 10:03:47 INFO - PROCESS | 1654 | |0;dojo.html;3043.9086827395186;3111.268851819822;3359.9248240563015;3389.1527118220447;3421.880234191804;5637.069883469233;5732.971335143318;5744.79893203738;5788.594798364116;5856.063029915214;5804.506616937555;5874.295084589845;5916.201312822778;5993.041113421853;6015.909761353583;5209.661372010807;5248.921266181011;5289.206619007141;5308.336457942757;5430.70131142789;5378.46215378463;5417.539509141728;5424.5117939385445;5441.347038355403;5463.426340234278;5192.169252919533;5227.60792940531;5264.973675131638;5268.683878967236;5282.709450980195;5249.4750524947585;5264.894702105937;5271.525562699376;5272.103742363769;5280.841574752725;5280.524752772269;5300.787968481295;5310.097283461821;5382.165764306539;5391.272178255925;5452.100403433429;5486.862828429281;5809.883802323972;5919.940800591982;5920.763169473216;5223.555997740196;6084.852717431988;6110.297315808694;6131.6934153292605;6176.752929882799;4993.825216117419;6200.503959683259;6207.906881396782;6231.501479881598;6244.500439964792;5742.684152371659;5848;5947.256592925884;5953.485587715904;6012.879742405171;5384.219288203189;6194.907076393858;6242.282137554149;6243.094751261133;6315.494760419156;6229.4393504584805;6232.8130156095385;6237.937620623736;6241.032639940817;6244.750209991549;6136.12084543884;6232.844178895473;6247.500199983991;6277.435030847154;6305.558610897193;6252.155958945576;6288.088227207029;6332.905006424908;6337.556371053982;6362.554621176566;6318.557700960981;6339.841503962438;6342.175517182829;6343.873122537523;6356.841078973062;4816.590589799906;4909.729964851896;4921.704697718148;4959.454460009396;4983.526564976321;4430;4478.865634030919;4512.165249428866;4515.0518391137575;4518.774061296922;5805.506531944832;5806.883862322815;5813.76744930198;5863.706814659336;5865.941340586625;5751.971240143773;5774.018416869142;6248.562600618004;6286.9056964145575;6301.054841773679;5903.822885313448;5913.586048976616;5916.171735956969;5931.644101353846;5949.8215053548465;4805.087033463631;5321.920171197435;5471.671699697951;5488.4237155098135;5496.7526461309335;5062.7468626568525;6325.810225693236;6327.778527751599;6414.230292364946;6436.195475565554;6256.0303153011355;6265.906011409741;6275.717592708431;6283.3088360281245;6301.4328710416285;6219.0982307566055;6263.342349053466;6448.161738974091;6455.128557644658;6462.547621666438;6143.447089761943;6163.9383606163365;6182.938170618237;6186.566940314135;6200.441960223421;6052.939470605414;6169.259688837368;6214.627122372492;6214.751409943552;6240.469560087262;6119.632822030692;6125.632462052291;6140.078988151813;6183.288921774054;6190.969045154746;5967.4629283362965;6010.1585777991995;6132.846678833065;6145.631262124287;6202.472789812917;6065.909011364744;6096.163860440966;6098.717372860173;6100.5292856393235;6106.15732346443;6102.938970610237;6178.907316390348;6204.875902482016;6213.689315534115;6235.594686345373;5907.113932910098;5982.282126144891;6017.81946541587;6082.847928801815;6092.939070609237 10:03:47 INFO - PROCESS | 1654 | |1;ext.html;482.7779221558083;498.2352089542553;515.078010361453;534.5402953460025;542.5496837624772;30800.075997720076;43643;44706.88232794178;44831;46953.76523117388;46135.538644613545;46365.536344636545;46444;46478.30282545764;46621;8850.73447796567;9765.072318129767;9956.751081222956;9957.651482198124;9963.551640176209;8755.124487551226;11999.64001079969;12203.145779795417;12205.57280495183;12410.565630202944;12222;12264.202826816228;12271.202371845846;12279.263244205331;12298.56955006575;8891.02198758136;11957.282563046245;12170.148089633729;12239.877601224052;12266.080043996692;11838.171328007043;12088.395580220953;12095.576654817127;12102;12125.09061820367;43305.35041974373;43627.781861090494;43647.90880227988;43680.12639747219;43827;3706.9814650926573;3731.328360895038;3823.063349479395;4031.4154447605224;4093.3246014407587;32858.6714132858;33883.26508759578;34176.48735268973;34561.13597160066;34626;3268.3790079884748;3333.0667413124365;3373.915652108717;3406.1654894550747;3453.8963831085107;527275.3636231794;528984;531826;532559;532683;65165;65531.67234163799;65793.01310480347;65843;66021.33978660151;67951;68195;68274;68307;68406;16607.91696041512;16860;16893.32426702918;16913.57716057084;16940.83059169417;11841.46713397882;12052.035837133008;12792.6801829955;12923.806142907864;12987.870121298854;17698.115094245237;17792.644147116986;17795.46613601594;17898.821011789976;17916.462506124837;6690.732370705215;6716.798496045127;6736.360045795642;6739.764108256286;6767.627780472121;938.8544775559799;965.7489052846311;975.190591808797;1026.9627676047096;1050.632278702448;31748.36503269968;31963.88126415611;32563.02310930676;32636.83681581577;32770.67229327724;1133.0482394788417;1175.9412029398495;1181.1908842442954;1181.9775893851756;1183.1008433590362;13833.30250601123;16668.083255420825;16715.91642041782;16826;17090;665593;672849.2715072884;673191;673652;674322;50790;62114;63088;63312;63543;1278.7506436244844;1330.0290787725207;1409.450314377414;1416.9433222670978;1443.141330908129;30262.394752104396;32734.181645459055;32891.01326960195;33249.33501329912;33364.666353337125;54155;55102;55102.72448637892;55164.72417637886;55220.44779552153;396153.019234902;411678.94160529005;418070;422996;424043;31382.058538243888;37341;44727.77636111798;46164.7691761539;47239.527604724884;50678;51149;51622.22566661428;52065.21902171395;52244.693882653235;42370.57629423789;42692.93267668209;43038.78480607577;43136.78431607947;44067;41729.1654166921;42239.788801055794;42492.57507424886;42731.57268427276;43281.91795205019;40461.797691012536;41328.96677583084;41463.58536414718;41546.16907661769;43356.78321608372;32245.51631725573;34442.82778586091;35020;37385;37514.249715005004;26546.203613891616;26944.55414856527;26997.595036074083;27403.31491712723;27718.722812771615 10:03:47 INFO - PROCESS | 1654 | |2;jquery.html;303.8662988285156;383.93665045267534;391.32496443634733;393.10176247274984;416.78743840641283;15515.456959006437;15876.96799708018;19915.402537923885;20559.794402055973;20567.485812854688;18094.5476363091;18286.90278583286;18328.083595820222;18963.810361896376;21869.781302186973;5846.941530584703;5881.823545293648;6107.908381374283;6163.445289923906;6185.814425567218;5945.346011938684;6009.549283803733;6031.60794548353;6040.818775436744;6045.03279475284;5666.461686139831;5923.614965027259;6000.939990600104;6002.699865006754;6005.369436209201;5929.940700592982;5950.166976623276;5977.312609049928;5986.3714309997695;5993;4989.675671081404;5139.12634852076;5141.2288156776785;5142.948570514284;5201.297824793643;25869.870650646626;25932.092376766912;26050.73949260521;26113;26182.214533564023;1925.7881633020497;2228.543148654538;2231.2865830388537;2233.798958093779;2247.5954328220914;14721.852781472155;15790.605234869105;16150.596235094099;16201.189940502927;16413.67172656552;1895.6682580548486;1895.8862468251812;1928.758905136858;1967.3409407848387;2000.3998800359834;82264;91178.54410727904;93644.12711745795;111950.44024779905;113305.73388532265;10615.893841061645;10894.618688346029;11191.832122518168;11237.83143252852;11305.208635395444;13686.931565342109;13894.305284735723;13956.651083722785;13956.651083722989;13957.441702331997;8499.320054395634;8649.308055355556;8686.044535101133;8777.736667899973;8778.209961103532;8698.782530436789;8712.520811355436;8716.912830871737;8806.251468625238;8806.647734090693;5590.740990282795;5745.13822926556;6710.832229194211;6840.31596840312;6841.247462779091;1758.5603599100225;1813.0481497214068;1850.500364901469;1868.271374164077;1881.617011496549;762.9014219523979;895.3016647015338;903.2051794420868;961.5817119553017;974.7027156717128;21674.458138546346;22682.319530414115;23472.413189670395;23473.647895281585;23507.529849403258;956.4791980750501;999.9050090241416;1027.3271007490107;1032.3238278927165;1044.7283706236433;9548.474833884204;11776.76446471083;12294;12405.317707526172;12468.189567678071;504844.0398162242;511734;511956;616187;622402;144700.27649861894;152603;153722;154285;155191;977.94621295828;1023.370627064365;1046.6075221791828;1047.502436342731;1052.0531521630594;19869.602607947472;20188.192472301533;20358.9820508971;20360.69458958087;20436.897815510827;58527.38259977892;58983;59663.0168491565;59905;59955.70022149861;86486;86961.69557456516;87148;87796.12203878135;88292.5585372069;8373.748787536384;8462.407631465985;8505.23452889243;8521.872171917303;8780.209781119729;12904.419301131284;13132.080754347104;13531.188128712309;13676.589702308947;13954.581362558733;9598.568064437257;11531.596394126502;11558.884411155781;11697.181197316106;11940.985016273715;11628.53485860556;11748.412579371168;11771.823422648496;11924.880751192724;12055.939720301343;11766.117541184616;11840.940795295968;11883.227590206609;12047.51809927593;12078.939605301917;8056;8553.74338769838;8598.226159645412;8613.22480976715;8627.22354988029;8043.396745244013;8436.367272454469;11610.477528511068;11901.82147267809;11905.880941190477 10:03:47 INFO - PROCESS | 1654 | |3;mootools.html;1390.1520072755623;1561.4066654671226;1577.1562214215396;1772.2999415230984;1911.1972971352022;7778.222177782226;8675.089115642853;8929.55352232389;8949.105089491064;8956.507392093432;8553.786155346119;8768.94772627284;8770.956145219265;8772.99110602281;8775.6050977706;2325.546518428907;2356.352003199117;2356.434455730621;2357.9410514737096;2358.1864256831363;2318.640610705335;2341.988290058547;2361.409647588103;2362.8109751219863;2366.6450032495177;2327.3367090379247;2347.6361164019604;2349.177787774274;2354.2466410748566;2364.7162340519076;2288.416453804269;2312.144506532585;2320.3155069254612;2321.013569233069;2338.2166974063707;1947.8344340731055;2307.7922986931258;2307.8730669813153;2309.3302942146756;2313.144136669426;8757.211850933447;8792.560371981375;8798.86801697975;8936;8976.506292153928;5275.762590683428;5374.946250537484;5416.133418653036;5418.485243901823;5425.674459532441;3615.7649752765988;3657.9085522861874;3698.130939229279;3721.3673675475234;3724.1434470072036;1090.0516550600948;1096.1723898456694;1098.9505472253768;1123.0622430270735;1127.6560649002051;11542.653720388402;11601.07191424684;11644.883551164465;11645.301281923112;11653.941730291379;4373.518912919576;4377.562243775661;4589.5639914208095;4590.173768721662;4603.309503574491;9182.770430739285;9185.219256363283;9195.218406435393;9202.585883635118;9202.76993075165;5208.010478009166;5213.374395072616;5213.843584692465;5217.791288348423;5224.555912747459;3643.562772467321;4195.8741237762915;4373.431453910971;4535.070310586322;4894.681845680019;3742.8877133686033;3778.7354885157774;3853.672437842815;3864.787436691009;3893.4743809585343;988.229181238635;1148.9080873530097;1152.2222499812592;1154.8845115488552;1164.9825252621217;859.4113032572583;944.8866136063579;948.8339540580371;959.4648562300264;983.7294743945329;5455.318085239345;6008.158857759917;6018.849528761816;6060.33336333003;6128.1114238435175;437.1955601692837;442.65251777355263;451.7244480866669;457.97023193492316;459.2697610798846;3984.2828290907337;4036.910034290725;4123.484564429446;4125.64931980779;4128.050548373891;10468.424236667057;10662.466876656135;10777.976092271221;10789.730256743644;10890.29213101146;9027.593758280893;10541.947290263499;10623.575056997634;10669.306495077795;10724.517396717167;8237.917620823835;8328.292095171857;8340.749777506684;8358.456700314582;8380.874286885579;5847.970760146342;6000.969995149996;6206;6234.532410069172;6314.62112273265;7006.894896576658;9539.952300238454;9683;9897.455639939872;9900;9872.506374681094;9915.355501892354;10052.748681283027;10168.542415591168;10173.389596624227;6428.228612566522;6732.596044237362;7156.926461030887;7686.500377475446;7763.029621297338;8499.78750531217;8531.701390451304;8623.698170564001;8626.870596941177;8628.352873534384;7386.298301661333;7466.738664146729;7520.247975202436;7613.5431874087735;7622.885656715264;7405.851882962418;7465.626718663936;7507.474476786572;7608.467407281437;7658.349040331412;7087.858242835217;7118.928810711826;7155.463340249398;7158.498905076594;7162.677679504331;4861.44093429246;5015.398152221756;5025.673331233458;5040.823571174991;5067.721275329893;5028.798848046184;5103.489651034867;5117.30916326306;5200.141976573823;5304 10:03:47 INFO - PROCESS | 1654 | |4;prototype.html;252.42321295839008;307.2211942725192;313.6158206197409;314.66331025802396;320.2410287618346;4242.218268327302;5405.783768649254;5418.972905135473;5431.809886653973;5506.476884695958;4806.942472656019;5019.171836646958;5359.517643412092;5421.701806400646;5534.391216966131;3874.3800991841313;3958.703097267702;4039.091853865227;4189.518205406387;4232.555581663927;4090.734102283357;4123.36087906375;4152.1280531088505;4240.194363071022;4247.575242475758;3570.9287213835873;4113.280175969208;4132.896677583054;4149.593235923172;4162.542120366757;3447.6897079262985;3697.927600995708;3861.555921069085;4150.875473735778;4153;2934.413117376533;3060.9846950765104;3500.5274287971306;3584.713222942158;3596.011096948334;4214.810333534968;4837.572915989756;5273.683578985235;5319.813806516753;5358.705271210082;4568.086382723435;4648.116857797041;4723.905521889543;4729.834455794031;4743.604479737744;4200.28595138824;5434.2125636197325;5699.857503562403;5701.403606990049;5710.257666503369;3534.840932158059;3883.708721845873;3971.43128464255;4001.419794129864;4001.97999010006;4827.468978412372;5620.747066382022;5890.0536640451;6094.299155597118;6096.939030609681;3913.5108111486065;3921.274564205631;3928.46965659638;3936.6653834424396;3937;3525.682688558042;3988.80055997199;3996.2407142642805;3996.4205190247253;3998.1603863189057;4199.257308217117;4382.737035777864;4638.5593368629925;4652.441706995114;4693.68576798497;5494.5054945054335;5687.687177205294;5694.4590263924865;5711.314642242957;5713.9429805270065;1320.5906169087614;1352.3373546961918;1353.729254149174;1354.7290541891662;1360.5238316426787;804.6401581327538;828.958552072394;832.9708460203985;840.6259214649423;851.0638297872291;4982.950170498321;5108.131617625013;5129.384473863086;5150.742462876841;5159.329287192641;5384.838454846361;5844.824655260349;5860.3260625027815;5910.734016969247;5922.526197904157;4113.115680128787;4401.6478681705385;4944.208926571731;5043.5712964398435;5328.333958255218;2722.659667541557;2793.594928735322;2841.687414384416;3069.7851150419256;3081.9075427737203;5284.357821089456;5879.853003674943;6092.878142437126;6117.571769976148;6183.443490085914;5029.371328583927;5163.380394352702;5787.450192231731;5946.316173640001;5977.252843394575;5941.821745347646;6017.338092809788;6082.756689732361;6109.7861574844665;6143.354947730513;4832.9275060873415;4899.521339848748;4979.829740011022;5731.512821410227;5907.4683278505145;5503.367112782082;5679.115960274078;5790.392008839011;5829;5883.911741323969;5140.871478213075;6053.031514957585;6129.141920131096;6169.198004259418;6200.627962322275;3857.8842634721004;3916.8433262669173;3996.320625493615;4003.2185677374455;4050.5746896575433;5617.269754931833;5632.211490391266;5662.943370566241;5667.3482549507335;5687.943120568907;4954.30639710448;5074.517920797518;5513.61404701667;5537.1140617501005;5546.500814926676;4324.4162038125705;5107.6169287302855;5240.2663627093;5566.888662226814;5575.247341608993;5375.731213439391;5376.327959005124;5386.111291636836;5419.972900135474;5420.539254163283;4879.853604391874;5300.01949639318;5545.445455454423;5574.609777315712;5584.553235741212;5007.449180590059;5502.404302752318;5567.5246059795;5575.609707320448;5592.440755924375 10:03:47 INFO - PROCESS | 1654 | |5;yui.html;738.841149152932;748.6032402826504;786.4494853602478;818.3575892924052;822.7079386817676;13091.279979601119;15614.609634759137;16250.674986500262;16265.349386024545;16307.83692163078;14711.264436778172;14986;15008.024478408895;15009.399624015026;15010.924945375285;699.9790006299794;727.4253339861503;869.8782170496136;900.4957223954574;901.364538000708;891.189017993626;907.6233363154283;908.4640062363204;909.790748127931;910.1126401758299;967.2697113679197;967.318040781251;968.8159249742527;970.5292932927524;971.9173870221005;756.7843164698062;946.8721722567468;968.1093394077466;970.2529052629507;972.7276362618478;714.0146597695199;714.4962801225124;714.5462631229207;715.9868785668273;717.1573401253559;14710.33803478846;14745.262736863115;14766.483173088998;14790;14810.851891481163;13936.58190254294;13984.510542130976;14054.367553460119;14089.577312680636;14103;12656.936715316457;12681.23912565249;12703.110782245247;12962.546310879074;13286.734265314639;3908.2769687607883;4599.494055653875;4603.240465323218;4762.428508578958;4766.904661906742;109167.45416272947;111138.44430777794;112472.31291530633;112719;113000.43499782449;9737.26970477217;11092.156940532244;11212.775744485229;11282.94358528202;11440;22336.888315558645;22341.106355745913;22359.77640223577;22378.664320035212;22379.664305035436;7813.492123011986;7908.248716371935;7952.801179970547;8010.79973000668;8181.468204566684;4301.225779359683;4304.160688665743;4388.692791504628;4400.647948164204;4525.253333200051;3443.2252743133095;3554.4668299754726;3559.7864128152396;3572.4105522588484;3572.7141828654167;628.3025841316136;648.1412128929188;649.8732747114362;663.3167837127767;664.4451882677921;513.278843225269;547.3349879895945;561.8286422641052;616.2143267334095;623.7130919776863;8599.78500537479;8854.690085846965;9012.684556040507;9194.448333100036;9316.161545460942;535.9624826262202;569.1661715586686;611.1932249430708;625.9734036180668;654.7773756922669;4314.892127696833;4355.912881742411;4477.878742737984;4616.8614941551805;4619.353290539327;146183;146518;155982;157640;157912.21043894708;135876;138562.30718846343;138855;140718.99999999796;140999.2950035243;113556;117840.41079794546;118935;119000.40499797446;119212;8161.530924433659;8499.107593702573;8634.654613815628;8741.606627701642;8840.885982575472;61921.69039154775;63343.366566333745;63505.364946351794;64848;65132;60187.69906150441;62245.06632400427;98097.52853707343;99803;100453;13472.461101555828;13876.72246555043;13904.304784760923;14211.431542738175;14295.07082039664;14704.338304776526;14831.555053348417;15014.62463438379;15083.170425626256;15392.381780650207;14003;14089.718205635625;14277.286135692966;14285;14630.561083167097;13791.379387927367;13806.792898106336;14223.786643200154;14698.191599462132;14705.191214483306;13555.86444135546;13561.593152205449;13869.167849929037;14280.714385712436;14311.856881431053;9475.952620236854;10068.899311006797;11814.763704725685;11869.109816763605;12231.938840305742;9086.409383390323;9395.530223488662;9686;9734.659286924923;10430.226861433423 10:03:47 INFO - PROCESS | 1654 | __end_tp_report 10:03:47 INFO - PROCESS | 1654 | __start_cc_report 10:03:47 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1254 10:03:47 INFO - PROCESS | 1654 | __end_cc_report 10:03:47 INFO - PROCESS | 1654 | __startTimestamp1468343027213__endTimestamp 10:03:47 INFO - PROCESS | 1654 | 10:03:47 INFO - PROCESS | 1654 | ------- Summary: start ------- 10:03:47 INFO - PROCESS | 1654 | Number of tests: 6 10:03:47 INFO - PROCESS | 1654 | 10:03:47 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5771.61 Median:6010.16 stddev:653.03 (10.9%) stddev-sans-first:618.98 10:03:47 INFO - PROCESS | 1654 | Values: 3043.9 3111.3 3359.9 3389.2 3421.9 5637.1 5733.0 5744.8 5788.6 5856.1 5804.5 5874.3 5916.2 5993.0 6015.9 5209.7 5248.9 5289.2 5308.3 5430.7 5378.5 5417.5 5424.5 5441.3 5463.4 5192.2 5227.6 5265.0 5268.7 5282.7 5249.5 5264.9 5271.5 5272.1 5280.8 5280.5 5300.8 5310.1 5382.2 5391.3 5452.1 5486.9 5809.9 5919.9 5920.8 5223.6 6084.9 6110.3 6131.7 6176.8 4993.8 6200.5 6207.9 6231.5 6244.5 5742.7 5848.0 5947.3 5953.5 6012.9 5384.2 6194.9 6242.3 6243.1 6315.5 6229.4 6232.8 6237.9 6241.0 6244.8 6136.1 6232.8 6247.5 6277.4 6305.6 6252.2 6288.1 6332.9 6337.6 6362.6 6318.6 6339.8 6342.2 6343.9 6356.8 4816.6 4909.7 4921.7 4959.5 4983.5 4430.0 4478.9 4512.2 4515.1 4518.8 5805.5 5806.9 5813.8 5863.7 5865.9 5752.0 5774.0 6248.6 6286.9 6301.1 5903.8 5913.6 5916.2 5931.6 5949.8 4805.1 5321.9 5471.7 5488.4 5496.8 5062.7 6325.8 6327.8 6414.2 6436.2 6256.0 6265.9 6275.7 6283.3 6301.4 6219.1 6263.3 6448.2 6455.1 6462.5 6143.4 6163.9 6182.9 6186.6 6200.4 6052.9 6169.3 6214.6 6214.8 6240.5 6119.6 6125.6 6140.1 6183.3 6191.0 5967.5 6010.2 6132.8 6145.6 6202.5 6065.9 6096.2 6098.7 6100.5 6106.2 6102.9 6178.9 6204.9 6213.7 6235.6 5907.1 5982.3 6017.8 6082.8 6092.9 10:03:47 INFO - PROCESS | 1654 | 10:03:47 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:69963.58 Median:226503.95 stddev:146393.06 (64.6%) stddev-sans-first:146710.79 10:03:47 INFO - PROCESS | 1654 | Values: 482.8 498.2 515.1 534.5 542.5 30800.1 43643.0 44706.9 44831.0 46953.8 46135.5 46365.5 46444.0 46478.3 46621.0 8850.7 9765.1 9956.8 9957.7 9963.6 8755.1 11999.6 12203.1 12205.6 12410.6 12222.0 12264.2 12271.2 12279.3 12298.6 8891.0 11957.3 12170.1 12239.9 12266.1 11838.2 12088.4 12095.6 12102.0 12125.1 43305.4 43627.8 43647.9 43680.1 43827.0 3707.0 3731.3 3823.1 4031.4 4093.3 32858.7 33883.3 34176.5 34561.1 34626.0 3268.4 3333.1 3373.9 3406.2 3453.9 527275.4 528984.0 531826.0 532559.0 532683.0 65165.0 65531.7 65793.0 65843.0 66021.3 67951.0 68195.0 68274.0 68307.0 68406.0 16607.9 16860.0 16893.3 16913.6 16940.8 11841.5 12052.0 12792.7 12923.8 12987.9 17698.1 17792.6 17795.5 17898.8 17916.5 6690.7 6716.8 6736.4 6739.8 6767.6 938.9 965.7 975.2 1027.0 1050.6 31748.4 31963.9 32563.0 32636.8 32770.7 1133.0 1175.9 1181.2 1182.0 1183.1 13833.3 16668.1 16715.9 16826.0 17090.0 665593.0 672849.3 673191.0 673652.0 674322.0 50790.0 62114.0 63088.0 63312.0 63543.0 1278.8 1330.0 1409.5 1416.9 1443.1 30262.4 32734.2 32891.0 33249.3 33364.7 54155.0 55102.0 55102.7 55164.7 55220.4 396153.0 411678.9 418070.0 422996.0 424043.0 31382.1 37341.0 44727.8 46164.8 47239.5 50678.0 51149.0 51622.2 52065.2 52244.7 42370.6 42692.9 43038.8 43136.8 44067.0 41729.2 42239.8 42492.6 42731.6 43281.9 40461.8 41329.0 41463.6 41546.2 43356.8 32245.5 34442.8 35020.0 37385.0 37514.2 26546.2 26944.6 26997.6 27403.3 27718.7 10:03:47 INFO - PROCESS | 1654 | 10:03:47 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:34666.98 Median:25900.98 stddev:93701.80 (361.8%) stddev-sans-first:93929.14 10:03:47 INFO - PROCESS | 1654 | Values: 303.9 383.9 391.3 393.1 416.8 15515.5 15877.0 19915.4 20559.8 20567.5 18094.5 18286.9 18328.1 18963.8 21869.8 5846.9 5881.8 6107.9 6163.4 6185.8 5945.3 6009.5 6031.6 6040.8 6045.0 5666.5 5923.6 6000.9 6002.7 6005.4 5929.9 5950.2 5977.3 5986.4 5993.0 4989.7 5139.1 5141.2 5142.9 5201.3 25869.9 25932.1 26050.7 26113.0 26182.2 1925.8 2228.5 2231.3 2233.8 2247.6 14721.9 15790.6 16150.6 16201.2 16413.7 1895.7 1895.9 1928.8 1967.3 2000.4 82264.0 91178.5 93644.1 111950.4 113305.7 10615.9 10894.6 11191.8 11237.8 11305.2 13686.9 13894.3 13956.7 13956.7 13957.4 8499.3 8649.3 8686.0 8777.7 8778.2 8698.8 8712.5 8716.9 8806.3 8806.6 5590.7 5745.1 6710.8 6840.3 6841.2 1758.6 1813.0 1850.5 1868.3 1881.6 762.9 895.3 903.2 961.6 974.7 21674.5 22682.3 23472.4 23473.6 23507.5 956.5 999.9 1027.3 1032.3 1044.7 9548.5 11776.8 12294.0 12405.3 12468.2 504844.0 511734.0 511956.0 616187.0 622402.0 144700.3 152603.0 153722.0 154285.0 155191.0 977.9 1023.4 1046.6 1047.5 1052.1 19869.6 20188.2 20359.0 20360.7 20436.9 58527.4 58983.0 59663.0 59905.0 59955.7 86486.0 86961.7 87148.0 87796.1 88292.6 8373.7 8462.4 8505.2 8521.9 8780.2 12904.4 13132.1 13531.2 13676.6 13954.6 9598.6 11531.6 11558.9 11697.2 11941.0 11628.5 11748.4 11771.8 11924.9 12055.9 11766.1 11840.9 11883.2 12047.5 12078.9 8056.0 8553.7 8598.2 8613.2 8627.2 8043.4 8436.4 11610.5 11901.8 11905.9 10:03:47 INFO - PROCESS | 1654 | 10:03:47 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5657.60 Median:5054.27 stddev:3179.65 (62.9%) stddev-sans-first:3172.39 10:03:47 INFO - PROCESS | 1654 | Values: 1390.2 1561.4 1577.2 1772.3 1911.2 7778.2 8675.1 8929.6 8949.1 8956.5 8553.8 8768.9 8771.0 8773.0 8775.6 2325.5 2356.4 2356.4 2357.9 2358.2 2318.6 2342.0 2361.4 2362.8 2366.6 2327.3 2347.6 2349.2 2354.2 2364.7 2288.4 2312.1 2320.3 2321.0 2338.2 1947.8 2307.8 2307.9 2309.3 2313.1 8757.2 8792.6 8798.9 8936.0 8976.5 5275.8 5374.9 5416.1 5418.5 5425.7 3615.8 3657.9 3698.1 3721.4 3724.1 1090.1 1096.2 1099.0 1123.1 1127.7 11542.7 11601.1 11644.9 11645.3 11653.9 4373.5 4377.6 4589.6 4590.2 4603.3 9182.8 9185.2 9195.2 9202.6 9202.8 5208.0 5213.4 5213.8 5217.8 5224.6 3643.6 4195.9 4373.4 4535.1 4894.7 3742.9 3778.7 3853.7 3864.8 3893.5 988.2 1148.9 1152.2 1154.9 1165.0 859.4 944.9 948.8 959.5 983.7 5455.3 6008.2 6018.8 6060.3 6128.1 437.2 442.7 451.7 458.0 459.3 3984.3 4036.9 4123.5 4125.6 4128.1 10468.4 10662.5 10778.0 10789.7 10890.3 9027.6 10541.9 10623.6 10669.3 10724.5 8237.9 8328.3 8340.7 8358.5 8380.9 5848.0 6001.0 6206.0 6234.5 6314.6 7006.9 9540.0 9683.0 9897.5 9900.0 9872.5 9915.4 10052.7 10168.5 10173.4 6428.2 6732.6 7156.9 7686.5 7763.0 8499.8 8531.7 8623.7 8626.9 8628.4 7386.3 7466.7 7520.2 7613.5 7622.9 7405.9 7465.6 7507.5 7608.5 7658.3 7087.9 7118.9 7155.5 7158.5 7162.7 4861.4 5015.4 5025.7 5040.8 5067.7 5028.8 5103.5 5117.3 5200.1 5304.0 10:03:47 INFO - PROCESS | 1654 | 10:03:47 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4565.78 Median:5091.07 stddev:1412.42 (27.7%) stddev-sans-first:1378.78 10:03:47 INFO - PROCESS | 1654 | Values: 252.4 307.2 313.6 314.7 320.2 4242.2 5405.8 5419.0 5431.8 5506.5 4806.9 5019.2 5359.5 5421.7 5534.4 3874.4 3958.7 4039.1 4189.5 4232.6 4090.7 4123.4 4152.1 4240.2 4247.6 3570.9 4113.3 4132.9 4149.6 4162.5 3447.7 3697.9 3861.6 4150.9 4153.0 2934.4 3061.0 3500.5 3584.7 3596.0 4214.8 4837.6 5273.7 5319.8 5358.7 4568.1 4648.1 4723.9 4729.8 4743.6 4200.3 5434.2 5699.9 5701.4 5710.3 3534.8 3883.7 3971.4 4001.4 4002.0 4827.5 5620.7 5890.1 6094.3 6096.9 3913.5 3921.3 3928.5 3936.7 3937.0 3525.7 3988.8 3996.2 3996.4 3998.2 4199.3 4382.7 4638.6 4652.4 4693.7 5494.5 5687.7 5694.5 5711.3 5713.9 1320.6 1352.3 1353.7 1354.7 1360.5 804.6 829.0 833.0 840.6 851.1 4983.0 5108.1 5129.4 5150.7 5159.3 5384.8 5844.8 5860.3 5910.7 5922.5 4113.1 4401.6 4944.2 5043.6 5328.3 2722.7 2793.6 2841.7 3069.8 3081.9 5284.4 5879.9 6092.9 6117.6 6183.4 5029.4 5163.4 5787.5 5946.3 5977.3 5941.8 6017.3 6082.8 6109.8 6143.4 4832.9 4899.5 4979.8 5731.5 5907.5 5503.4 5679.1 5790.4 5829.0 5883.9 5140.9 6053.0 6129.1 6169.2 6200.6 3857.9 3916.8 3996.3 4003.2 4050.6 5617.3 5632.2 5662.9 5667.3 5687.9 4954.3 5074.5 5513.6 5537.1 5546.5 4324.4 5107.6 5240.3 5566.9 5575.2 5375.7 5376.3 5386.1 5420.0 5420.5 4879.9 5300.0 5545.4 5574.6 5584.6 5007.4 5502.4 5567.5 5575.6 5592.4 10:03:47 INFO - PROCESS | 1654 | 10:03:47 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:25448.08 Median:4104.75 stddev:41088.73 (1001.0%) stddev-sans-first:41162.11 10:03:47 INFO - PROCESS | 1654 | Values: 738.8 748.6 786.4 818.4 822.7 13091.3 15614.6 16250.7 16265.3 16307.8 14711.3 14986.0 15008.0 15009.4 15010.9 700.0 727.4 869.9 900.5 901.4 891.2 907.6 908.5 909.8 910.1 967.3 967.3 968.8 970.5 971.9 756.8 946.9 968.1 970.3 972.7 714.0 714.5 714.5 716.0 717.2 14710.3 14745.3 14766.5 14790.0 14810.9 13936.6 13984.5 14054.4 14089.6 14103.0 12656.9 12681.2 12703.1 12962.5 13286.7 3908.3 4599.5 4603.2 4762.4 4766.9 109167.5 111138.4 112472.3 112719.0 113000.4 9737.3 11092.2 11212.8 11282.9 11440.0 22336.9 22341.1 22359.8 22378.7 22379.7 7813.5 7908.2 7952.8 8010.8 8181.5 4301.2 4304.2 4388.7 4400.6 4525.3 3443.2 3554.5 3559.8 3572.4 3572.7 628.3 648.1 649.9 663.3 664.4 513.3 547.3 561.8 616.2 623.7 8599.8 8854.7 9012.7 9194.4 9316.2 536.0 569.2 611.2 626.0 654.8 4314.9 4355.9 4477.9 4616.9 4619.4 146183.0 146518.0 155982.0 157640.0 157912.2 135876.0 138562.3 138855.0 140719.0 140999.3 113556.0 117840.4 118935.0 119000.4 119212.0 8161.5 8499.1 8634.7 8741.6 8840.9 61921.7 63343.4 63505.4 64848.0 65132.0 60187.7 62245.1 98097.5 99803.0 100453.0 13472.5 13876.7 13904.3 14211.4 14295.1 14704.3 14831.6 15014.6 15083.2 15392.4 14003.0 14089.7 14277.3 14285.0 14630.6 13791.4 13806.8 14223.8 14698.2 14705.2 13555.9 13561.6 13869.2 14280.7 14311.9 9476.0 10068.9 11814.8 11869.1 12231.9 9086.4 9395.5 9686.0 9734.7 10430.2 10:03:47 INFO - PROCESS | 1654 | -------- Summary: end -------- 10:03:47 INFO - PROCESS | 1654 | 10:03:47 INFO - TEST-INFO | 1654: exit 0 10:03:47 INFO - TEST-OK | dromaeo_css | took 1115892ms 10:03:47 INFO - TEST-START | kraken 10:03:47 INFO - operating with platform_type : mac_ 10:03:47 INFO - Initialising browser for kraken test... 10:03:47 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmp8eGnVu/profile http://localhost:49272/getInfo.html) 10:03:50 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 10:03:50 INFO - PROCESS | 1664 | colorDepth:24 10:03:50 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 10:03:50 INFO - PROCESS | 1664 | __metrics 10:03:50 INFO - PROCESS | 1664 | 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" 10:03:50 INFO - PROCESS | 1664 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:03:50 INFO - TEST-INFO | 1664: exit 0 10:03:50 INFO - Browser initialized. 10:03:50 INFO - Running cycle 1/1 for kraken test... 10:03:50 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmp8eGnVu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:04:02 INFO - PROCESS | 1665 | RSS: Main: 243916800 10:04:02 INFO - PROCESS | 1665 | 10:04:05 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 10:04:06 INFO - PROCESS | 1665 | RSS: Main: 293916672 10:04:06 INFO - PROCESS | 1665 | 10:04:08 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 10:04:09 INFO - PROCESS | 1665 | RSS: Main: 304033792 10:04:09 INFO - PROCESS | 1665 | 10:04:12 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 10:04:13 INFO - PROCESS | 1665 | RSS: Main: 389832704 10:04:13 INFO - PROCESS | 1665 | 10:04:15 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 10:04:16 INFO - PROCESS | 1665 | RSS: Main: 291590144 10:04:16 INFO - PROCESS | 1665 | 10:04:18 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:04:18 INFO - PROCESS | 1665 | RSS: Main: 295264256 10:04:18 INFO - PROCESS | 1665 | 10:04:21 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 10:04:22 INFO - PROCESS | 1665 | RSS: Main: 332836864 10:04:22 INFO - PROCESS | 1665 | 10:04:25 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 10:04:25 INFO - PROCESS | 1665 | RSS: Main: 335585280 10:04:25 INFO - PROCESS | 1665 | 10:04:28 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 10:04:29 INFO - PROCESS | 1665 | RSS: Main: 332034048 10:04:29 INFO - PROCESS | 1665 | 10:04:30 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:04:31 INFO - PROCESS | 1665 | RSS: Main: 314081280 10:04:31 INFO - PROCESS | 1665 | 10:04:33 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:04:33 INFO - PROCESS | 1665 | RSS: Main: 320471040 10:04:33 INFO - PROCESS | 1665 | 10:04:36 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:04:36 INFO - PROCESS | 1665 | RSS: Main: 318730240 10:04:36 INFO - PROCESS | 1665 | 10:04:39 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:04:40 INFO - PROCESS | 1665 | RSS: Main: 327327744 10:04:40 INFO - PROCESS | 1665 | 10:04:43 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:04:43 INFO - PROCESS | 1665 | RSS: Main: 328900608 10:04:43 INFO - PROCESS | 1665 | 10:04:45 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 10:04:45 INFO - PROCESS | 1665 | RSS: Main: 371847168 10:04:45 INFO - PROCESS | 1665 | 10:04:45 INFO - PROCESS | 1665 | __start_tp_report 10:04:45 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 10:04:45 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 10:04:45 INFO - PROCESS | 1665 | |i|pagename|runs| 10:04:45 INFO - PROCESS | 1665 | |0;ai-astar;101;112;113;112;117;113;115;114;113;116 10:04:45 INFO - PROCESS | 1665 | |1;audio-beat-detection;145;108;106;158;109;108;132;107;139;109 10:04:45 INFO - PROCESS | 1665 | |2;audio-dft;182;184;181;181;184;183;184;183;186;182 10:04:45 INFO - PROCESS | 1665 | |3;audio-fft;77;74;59;85;68;56;62;64;99;55 10:04:45 INFO - PROCESS | 1665 | |4;audio-oscillator;84;85;92;72;71;69;95;69;68;101 10:04:45 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;89;85;88;85;87;85;87;87;91;83 10:04:45 INFO - PROCESS | 1665 | |6;imaging-darkroom;93;93;101;93;93;93;94;93;93;92 10:04:45 INFO - PROCESS | 1665 | |7;imaging-desaturate;87;87;85;102;139;105;84;86;85;84 10:04:45 INFO - PROCESS | 1665 | |8;json-parse-financial;56;56;53;55;53;55;56;54;54;53 10:04:45 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;65;72;77;78;73;71;72;72;71;73 10:04:45 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;84;99;85;80;82;83;84;86;94;92 10:04:45 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;138;134;140;148;150;148;149;137;184;138 10:04:45 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;162;163;164;162;162;164;164;164;162;164 10:04:45 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;66;66;70;77;86;84;83;85;67;66 10:04:45 INFO - PROCESS | 1665 | __end_tp_report 10:04:45 INFO - PROCESS | 1665 | __start_cc_report 10:04:45 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,2064 10:04:45 INFO - PROCESS | 1665 | __end_cc_report 10:04:45 INFO - PROCESS | 1665 | __startTimestamp1468343085917__endTimestamp 10:04:45 INFO - PROCESS | 1665 | 10:04:45 INFO - PROCESS | 1665 | ------- Summary: start ------- 10:04:45 INFO - PROCESS | 1665 | Number of tests: 14 10:04:45 INFO - PROCESS | 1665 | 10:04:45 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:112.60 Median:113.50 stddev:4.40 (3.9%) stddev-sans-first:1.76 10:04:45 INFO - PROCESS | 1665 | Values: 101.0 112.0 113.0 112.0 117.0 113.0 115.0 114.0 113.0 116.0 10:04:45 INFO - PROCESS | 1665 | 10:04:45 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:122.10 Median:120.50 stddev:19.51 (16.2%) stddev-sans-first:18.85 10:04:45 INFO - PROCESS | 1665 | Values: 145.0 108.0 106.0 158.0 109.0 108.0 132.0 107.0 139.0 109.0 10:04:45 INFO - PROCESS | 1665 | 10:04:45 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:183.00 Median:183.50 stddev:1.56 (0.9%) stddev-sans-first:1.62 10:04:45 INFO - PROCESS | 1665 | Values: 182.0 184.0 181.0 181.0 184.0 183.0 184.0 183.0 186.0 182.0 10:04:45 INFO - PROCESS | 1665 | 10:04:45 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:69.90 Median:71.00 stddev:14.05 (19.8%) stddev-sans-first:14.67 10:04:45 INFO - PROCESS | 1665 | Values: 77.0 74.0 59.0 85.0 68.0 56.0 62.0 64.0 99.0 55.0 10:04:45 INFO - PROCESS | 1665 | 10:04:45 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:80.60 Median:78.00 stddev:12.38 (15.9%) stddev-sans-first:13.07 10:04:45 INFO - PROCESS | 1665 | Values: 84.0 85.0 92.0 72.0 71.0 69.0 95.0 69.0 68.0 101.0 10:04:45 INFO - PROCESS | 1665 | 10:04:45 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:87.00 stddev:2.31 (2.7%) stddev-sans-first:2.30 10:04:45 INFO - PROCESS | 1665 | Values: 89.0 85.0 88.0 85.0 87.0 85.0 87.0 87.0 91.0 83.0 10:04:45 INFO - PROCESS | 1665 | 10:04:45 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:93.80 Median:93.00 stddev:2.57 (2.8%) stddev-sans-first:2.71 10:04:45 INFO - PROCESS | 1665 | Values: 93.0 93.0 101.0 93.0 93.0 93.0 94.0 93.0 93.0 92.0 10:04:45 INFO - PROCESS | 1665 | 10:04:45 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:85.00 stddev:17.42 (20.5%) stddev-sans-first:18.27 10:04:45 INFO - PROCESS | 1665 | Values: 87.0 87.0 85.0 102.0 139.0 105.0 84.0 86.0 85.0 84.0 10:04:45 INFO - PROCESS | 1665 | 10:04:45 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:1.27 (2.3%) stddev-sans-first:1.22 10:04:45 INFO - PROCESS | 1665 | Values: 56.0 56.0 53.0 55.0 53.0 55.0 56.0 54.0 54.0 53.0 10:04:45 INFO - PROCESS | 1665 | 10:04:45 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:72.40 Median:72.50 stddev:3.53 (4.9%) stddev-sans-first:2.54 10:04:45 INFO - PROCESS | 1665 | Values: 65.0 72.0 77.0 78.0 73.0 71.0 72.0 72.0 71.0 73.0 10:04:45 INFO - PROCESS | 1665 | 10:04:45 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:85.50 stddev:6.06 (7.1%) stddev-sans-first:6.34 10:04:45 INFO - PROCESS | 1665 | Values: 84.0 99.0 85.0 80.0 82.0 83.0 84.0 86.0 94.0 92.0 10:04:45 INFO - PROCESS | 1665 | 10:04:45 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:146.60 Median:148.00 stddev:14.39 (9.7%) stddev-sans-first:14.92 10:04:45 INFO - PROCESS | 1665 | Values: 138.0 134.0 140.0 148.0 150.0 148.0 149.0 137.0 184.0 138.0 10:04:45 INFO - PROCESS | 1665 | 10:04:45 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.10 Median:164.00 stddev:0.99 (0.6%) stddev-sans-first:0.97 10:04:45 INFO - PROCESS | 1665 | Values: 162.0 163.0 164.0 162.0 162.0 164.0 164.0 164.0 162.0 164.0 10:04:45 INFO - PROCESS | 1665 | 10:04:45 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.00 Median:80.00 stddev:8.83 (11.0%) stddev-sans-first:8.75 10:04:45 INFO - PROCESS | 1665 | Values: 66.0 66.0 70.0 77.0 86.0 84.0 83.0 85.0 67.0 66.0 10:04:45 INFO - PROCESS | 1665 | -------- Summary: end -------- 10:04:45 INFO - PROCESS | 1665 | 10:04:46 INFO - TEST-INFO | 1665: exit 0 10:04:46 INFO - TEST-OK | kraken | took 58964ms 10:04:46 INFO - SUITE-END | took 1174s 10:04:47 INFO - Completed test suite (00:19:35) 10:04:47 INFO - javascript benchmark 10:04:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3043.9086827395186, 3111.268851819822, 3359.9248240563015, 3389.1527118220447, 3421.880234191804, 5637.069883469233, 5732.971335143318, 5744.79893203738, 5788.594798364116, 5856.063029915214, 5804.506616937555, 5874.295084589845, 5916.201312822778, 5993.041113421853, 6015.909761353583, 5209.661372010807, 5248.921266181011, 5289.206619007141, 5308.336457942757, 5430.70131142789, 5378.46215378463, 5417.539509141728, 5424.5117939385445, 5441.347038355403, 5463.426340234278, 5192.169252919533, 5227.60792940531, 5264.973675131638, 5268.683878967236, 5282.709450980195, 5249.4750524947585, 5264.894702105937, 5271.525562699376, 5272.103742363769, 5280.841574752725, 5280.524752772269, 5300.787968481295, 5310.097283461821, 5382.165764306539, 5391.272178255925, 5452.100403433429, 5486.862828429281, 5809.883802323972, 5919.940800591982, 5920.763169473216, 5223.555997740196, 6084.852717431988, 6110.297315808694, 6131.6934153292605, 6176.752929882799, 4993.825216117419, 6200.503959683259, 6207.906881396782, 6231.501479881598, 6244.500439964792, 5742.684152371659, 5848.0, 5947.256592925884, 5953.485587715904, 6012.879742405171, 5384.219288203189, 6194.907076393858, 6242.282137554149, 6243.094751261133, 6315.494760419156, 6229.4393504584805, 6232.8130156095385, 6237.937620623736, 6241.032639940817, 6244.750209991549, 6136.12084543884, 6232.844178895473, 6247.500199983991, 6277.435030847154, 6305.558610897193, 6252.155958945576, 6288.088227207029, 6332.905006424908, 6337.556371053982, 6362.554621176566, 6318.557700960981, 6339.841503962438, 6342.175517182829, 6343.873122537523, 6356.841078973062, 4816.590589799906, 4909.729964851896, 4921.704697718148, 4959.454460009396, 4983.526564976321, 4430.0, 4478.865634030919, 4512.165249428866, 4515.0518391137575, 4518.774061296922, 5805.506531944832, 5806.883862322815, 5813.76744930198, 5863.706814659336, 5865.941340586625, 5751.971240143773, 5774.018416869142, 6248.562600618004, 6286.9056964145575, 6301.054841773679, 5903.822885313448, 5913.586048976616, 5916.171735956969, 5931.644101353846, 5949.8215053548465, 4805.087033463631, 5321.920171197435, 5471.671699697951, 5488.4237155098135, 5496.7526461309335, 5062.7468626568525, 6325.810225693236, 6327.778527751599, 6414.230292364946, 6436.195475565554, 6256.0303153011355, 6265.906011409741, 6275.717592708431, 6283.3088360281245, 6301.4328710416285, 6219.0982307566055, 6263.342349053466, 6448.161738974091, 6455.128557644658, 6462.547621666438, 6143.447089761943, 6163.9383606163365, 6182.938170618237, 6186.566940314135, 6200.441960223421, 6052.939470605414, 6169.259688837368, 6214.627122372492, 6214.751409943552, 6240.469560087262, 6119.632822030692, 6125.632462052291, 6140.078988151813, 6183.288921774054, 6190.969045154746, 5967.4629283362965, 6010.1585777991995, 6132.846678833065, 6145.631262124287, 6202.472789812917, 6065.909011364744, 6096.163860440966, 6098.717372860173, 6100.5292856393235, 6106.15732346443, 6102.938970610237, 6178.907316390348, 6204.875902482016, 6213.689315534115, 6235.594686345373, 5907.113932910098, 5982.282126144891, 6017.81946541587, 6082.847928801815, 6092.939070609237], "value": 5729.822583684901, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [482.7779221558083, 498.2352089542553, 515.078010361453, 534.5402953460025, 542.5496837624772, 30800.075997720076, 43643.0, 44706.88232794178, 44831.0, 46953.76523117388, 46135.538644613545, 46365.536344636545, 46444.0, 46478.30282545764, 46621.0, 8850.73447796567, 9765.072318129767, 9956.751081222956, 9957.651482198124, 9963.551640176209, 8755.124487551226, 11999.64001079969, 12203.145779795417, 12205.57280495183, 12410.565630202944, 12222.0, 12264.202826816228, 12271.202371845846, 12279.263244205331, 12298.56955006575, 8891.02198758136, 11957.282563046245, 12170.148089633729, 12239.877601224052, 12266.080043996692, 11838.171328007043, 12088.395580220953, 12095.576654817127, 12102.0, 12125.09061820367, 43305.35041974373, 43627.781861090494, 43647.90880227988, 43680.12639747219, 43827.0, 3706.9814650926573, 3731.328360895038, 3823.063349479395, 4031.4154447605224, 4093.3246014407587, 32858.6714132858, 33883.26508759578, 34176.48735268973, 34561.13597160066, 34626.0, 3268.3790079884748, 3333.0667413124365, 3373.915652108717, 3406.1654894550747, 3453.8963831085107, 527275.3636231794, 528984.0, 531826.0, 532559.0, 532683.0, 65165.0, 65531.67234163799, 65793.01310480347, 65843.0, 66021.33978660151, 67951.0, 68195.0, 68274.0, 68307.0, 68406.0, 16607.91696041512, 16860.0, 16893.32426702918, 16913.57716057084, 16940.83059169417, 11841.46713397882, 12052.035837133008, 12792.6801829955, 12923.806142907864, 12987.870121298854, 17698.115094245237, 17792.644147116986, 17795.46613601594, 17898.821011789976, 17916.462506124837, 6690.732370705215, 6716.798496045127, 6736.360045795642, 6739.764108256286, 6767.627780472121, 938.8544775559799, 965.7489052846311, 975.190591808797, 1026.9627676047096, 1050.632278702448, 31748.36503269968, 31963.88126415611, 32563.02310930676, 32636.83681581577, 32770.67229327724, 1133.0482394788417, 1175.9412029398495, 1181.1908842442954, 1181.9775893851756, 1183.1008433590362, 13833.30250601123, 16668.083255420825, 16715.91642041782, 16826.0, 17090.0, 665593.0, 672849.2715072884, 673191.0, 673652.0, 674322.0, 50790.0, 62114.0, 63088.0, 63312.0, 63543.0, 1278.7506436244844, 1330.0290787725207, 1409.450314377414, 1416.9433222670978, 1443.141330908129, 30262.394752104396, 32734.181645459055, 32891.01326960195, 33249.33501329912, 33364.666353337125, 54155.0, 55102.0, 55102.72448637892, 55164.72417637886, 55220.44779552153, 396153.019234902, 411678.94160529005, 418070.0, 422996.0, 424043.0, 31382.058538243888, 37341.0, 44727.77636111798, 46164.7691761539, 47239.527604724884, 50678.0, 51149.0, 51622.22566661428, 52065.21902171395, 52244.693882653235, 42370.57629423789, 42692.93267668209, 43038.78480607577, 43136.78431607947, 44067.0, 41729.1654166921, 42239.788801055794, 42492.57507424886, 42731.57268427276, 43281.91795205019, 40461.797691012536, 41328.96677583084, 41463.58536414718, 41546.16907661769, 43356.78321608372, 32245.51631725573, 34442.82778586091, 35020.0, 37385.0, 37514.249715005004, 26546.203613891616, 26944.55414856527, 26997.595036074083, 27403.31491712723, 27718.722812771615], "value": 21468.21120556016, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.8662988285156, 383.93665045267534, 391.32496443634733, 393.10176247274984, 416.78743840641283, 15515.456959006437, 15876.96799708018, 19915.402537923885, 20559.794402055973, 20567.485812854688, 18094.5476363091, 18286.90278583286, 18328.083595820222, 18963.810361896376, 21869.781302186973, 5846.941530584703, 5881.823545293648, 6107.908381374283, 6163.445289923906, 6185.814425567218, 5945.346011938684, 6009.549283803733, 6031.60794548353, 6040.818775436744, 6045.03279475284, 5666.461686139831, 5923.614965027259, 6000.939990600104, 6002.699865006754, 6005.369436209201, 5929.940700592982, 5950.166976623276, 5977.312609049928, 5986.3714309997695, 5993.0, 4989.675671081404, 5139.12634852076, 5141.2288156776785, 5142.948570514284, 5201.297824793643, 25869.870650646626, 25932.092376766912, 26050.73949260521, 26113.0, 26182.214533564023, 1925.7881633020497, 2228.543148654538, 2231.2865830388537, 2233.798958093779, 2247.5954328220914, 14721.852781472155, 15790.605234869105, 16150.596235094099, 16201.189940502927, 16413.67172656552, 1895.6682580548486, 1895.8862468251812, 1928.758905136858, 1967.3409407848387, 2000.3998800359834, 82264.0, 91178.54410727904, 93644.12711745795, 111950.44024779905, 113305.73388532265, 10615.893841061645, 10894.618688346029, 11191.832122518168, 11237.83143252852, 11305.208635395444, 13686.931565342109, 13894.305284735723, 13956.651083722785, 13956.651083722989, 13957.441702331997, 8499.320054395634, 8649.308055355556, 8686.044535101133, 8777.736667899973, 8778.209961103532, 8698.782530436789, 8712.520811355436, 8716.912830871737, 8806.251468625238, 8806.647734090693, 5590.740990282795, 5745.13822926556, 6710.832229194211, 6840.31596840312, 6841.247462779091, 1758.5603599100225, 1813.0481497214068, 1850.500364901469, 1868.271374164077, 1881.617011496549, 762.9014219523979, 895.3016647015338, 903.2051794420868, 961.5817119553017, 974.7027156717128, 21674.458138546346, 22682.319530414115, 23472.413189670395, 23473.647895281585, 23507.529849403258, 956.4791980750501, 999.9050090241416, 1027.3271007490107, 1032.3238278927165, 1044.7283706236433, 9548.474833884204, 11776.76446471083, 12294.0, 12405.317707526172, 12468.189567678071, 504844.0398162242, 511734.0, 511956.0, 616187.0, 622402.0, 144700.27649861894, 152603.0, 153722.0, 154285.0, 155191.0, 977.94621295828, 1023.370627064365, 1046.6075221791828, 1047.502436342731, 1052.0531521630594, 19869.602607947472, 20188.192472301533, 20358.9820508971, 20360.69458958087, 20436.897815510827, 58527.38259977892, 58983.0, 59663.0168491565, 59905.0, 59955.70022149861, 86486.0, 86961.69557456516, 87148.0, 87796.12203878135, 88292.5585372069, 8373.748787536384, 8462.407631465985, 8505.23452889243, 8521.872171917303, 8780.209781119729, 12904.419301131284, 13132.080754347104, 13531.188128712309, 13676.589702308947, 13954.581362558733, 9598.568064437257, 11531.596394126502, 11558.884411155781, 11697.181197316106, 11940.985016273715, 11628.53485860556, 11748.412579371168, 11771.823422648496, 11924.880751192724, 12055.939720301343, 11766.117541184616, 11840.940795295968, 11883.227590206609, 12047.51809927593, 12078.939605301917, 8056.0, 8553.74338769838, 8598.226159645412, 8613.22480976715, 8627.22354988029, 8043.396745244013, 8436.367272454469, 11610.477528511068, 11901.82147267809, 11905.880941190477], "value": 9913.528437551713, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1390.1520072755623, 1561.4066654671226, 1577.1562214215396, 1772.2999415230984, 1911.1972971352022, 7778.222177782226, 8675.089115642853, 8929.55352232389, 8949.105089491064, 8956.507392093432, 8553.786155346119, 8768.94772627284, 8770.956145219265, 8772.99110602281, 8775.6050977706, 2325.546518428907, 2356.352003199117, 2356.434455730621, 2357.9410514737096, 2358.1864256831363, 2318.640610705335, 2341.988290058547, 2361.409647588103, 2362.8109751219863, 2366.6450032495177, 2327.3367090379247, 2347.6361164019604, 2349.177787774274, 2354.2466410748566, 2364.7162340519076, 2288.416453804269, 2312.144506532585, 2320.3155069254612, 2321.013569233069, 2338.2166974063707, 1947.8344340731055, 2307.7922986931258, 2307.8730669813153, 2309.3302942146756, 2313.144136669426, 8757.211850933447, 8792.560371981375, 8798.86801697975, 8936.0, 8976.506292153928, 5275.762590683428, 5374.946250537484, 5416.133418653036, 5418.485243901823, 5425.674459532441, 3615.7649752765988, 3657.9085522861874, 3698.130939229279, 3721.3673675475234, 3724.1434470072036, 1090.0516550600948, 1096.1723898456694, 1098.9505472253768, 1123.0622430270735, 1127.6560649002051, 11542.653720388402, 11601.07191424684, 11644.883551164465, 11645.301281923112, 11653.941730291379, 4373.518912919576, 4377.562243775661, 4589.5639914208095, 4590.173768721662, 4603.309503574491, 9182.770430739285, 9185.219256363283, 9195.218406435393, 9202.585883635118, 9202.76993075165, 5208.010478009166, 5213.374395072616, 5213.843584692465, 5217.791288348423, 5224.555912747459, 3643.562772467321, 4195.8741237762915, 4373.431453910971, 4535.070310586322, 4894.681845680019, 3742.8877133686033, 3778.7354885157774, 3853.672437842815, 3864.787436691009, 3893.4743809585343, 988.229181238635, 1148.9080873530097, 1152.2222499812592, 1154.8845115488552, 1164.9825252621217, 859.4113032572583, 944.8866136063579, 948.8339540580371, 959.4648562300264, 983.7294743945329, 5455.318085239345, 6008.158857759917, 6018.849528761816, 6060.33336333003, 6128.1114238435175, 437.1955601692837, 442.65251777355263, 451.7244480866669, 457.97023193492316, 459.2697610798846, 3984.2828290907337, 4036.910034290725, 4123.484564429446, 4125.64931980779, 4128.050548373891, 10468.424236667057, 10662.466876656135, 10777.976092271221, 10789.730256743644, 10890.29213101146, 9027.593758280893, 10541.947290263499, 10623.575056997634, 10669.306495077795, 10724.517396717167, 8237.917620823835, 8328.292095171857, 8340.749777506684, 8358.456700314582, 8380.874286885579, 5847.970760146342, 6000.969995149996, 6206.0, 6234.532410069172, 6314.62112273265, 7006.894896576658, 9539.952300238454, 9683.0, 9897.455639939872, 9900.0, 9872.506374681094, 9915.355501892354, 10052.748681283027, 10168.542415591168, 10173.389596624227, 6428.228612566522, 6732.596044237362, 7156.926461030887, 7686.500377475446, 7763.029621297338, 8499.78750531217, 8531.701390451304, 8623.698170564001, 8626.870596941177, 8628.352873534384, 7386.298301661333, 7466.738664146729, 7520.247975202436, 7613.5431874087735, 7622.885656715264, 7405.851882962418, 7465.626718663936, 7507.474476786572, 7608.467407281437, 7658.349040331412, 7087.858242835217, 7118.928810711826, 7155.463340249398, 7158.498905076594, 7162.677679504331, 4861.44093429246, 5015.398152221756, 5025.673331233458, 5040.823571174991, 5067.721275329893, 5028.798848046184, 5103.489651034867, 5117.30916326306, 5200.141976573823, 5304.0], "value": 4470.4548885606855, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [252.42321295839008, 307.2211942725192, 313.6158206197409, 314.66331025802396, 320.2410287618346, 4242.218268327302, 5405.783768649254, 5418.972905135473, 5431.809886653973, 5506.476884695958, 4806.942472656019, 5019.171836646958, 5359.517643412092, 5421.701806400646, 5534.391216966131, 3874.3800991841313, 3958.703097267702, 4039.091853865227, 4189.518205406387, 4232.555581663927, 4090.734102283357, 4123.36087906375, 4152.1280531088505, 4240.194363071022, 4247.575242475758, 3570.9287213835873, 4113.280175969208, 4132.896677583054, 4149.593235923172, 4162.542120366757, 3447.6897079262985, 3697.927600995708, 3861.555921069085, 4150.875473735778, 4153.0, 2934.413117376533, 3060.9846950765104, 3500.5274287971306, 3584.713222942158, 3596.011096948334, 4214.810333534968, 4837.572915989756, 5273.683578985235, 5319.813806516753, 5358.705271210082, 4568.086382723435, 4648.116857797041, 4723.905521889543, 4729.834455794031, 4743.604479737744, 4200.28595138824, 5434.2125636197325, 5699.857503562403, 5701.403606990049, 5710.257666503369, 3534.840932158059, 3883.708721845873, 3971.43128464255, 4001.419794129864, 4001.97999010006, 4827.468978412372, 5620.747066382022, 5890.0536640451, 6094.299155597118, 6096.939030609681, 3913.5108111486065, 3921.274564205631, 3928.46965659638, 3936.6653834424396, 3937.0, 3525.682688558042, 3988.80055997199, 3996.2407142642805, 3996.4205190247253, 3998.1603863189057, 4199.257308217117, 4382.737035777864, 4638.5593368629925, 4652.441706995114, 4693.68576798497, 5494.5054945054335, 5687.687177205294, 5694.4590263924865, 5711.314642242957, 5713.9429805270065, 1320.5906169087614, 1352.3373546961918, 1353.729254149174, 1354.7290541891662, 1360.5238316426787, 804.6401581327538, 828.958552072394, 832.9708460203985, 840.6259214649423, 851.0638297872291, 4982.950170498321, 5108.131617625013, 5129.384473863086, 5150.742462876841, 5159.329287192641, 5384.838454846361, 5844.824655260349, 5860.3260625027815, 5910.734016969247, 5922.526197904157, 4113.115680128787, 4401.6478681705385, 4944.208926571731, 5043.5712964398435, 5328.333958255218, 2722.659667541557, 2793.594928735322, 2841.687414384416, 3069.7851150419256, 3081.9075427737203, 5284.357821089456, 5879.853003674943, 6092.878142437126, 6117.571769976148, 6183.443490085914, 5029.371328583927, 5163.380394352702, 5787.450192231731, 5946.316173640001, 5977.252843394575, 5941.821745347646, 6017.338092809788, 6082.756689732361, 6109.7861574844665, 6143.354947730513, 4832.9275060873415, 4899.521339848748, 4979.829740011022, 5731.512821410227, 5907.4683278505145, 5503.367112782082, 5679.115960274078, 5790.392008839011, 5829.0, 5883.911741323969, 5140.871478213075, 6053.031514957585, 6129.141920131096, 6169.198004259418, 6200.627962322275, 3857.8842634721004, 3916.8433262669173, 3996.320625493615, 4003.2185677374455, 4050.5746896575433, 5617.269754931833, 5632.211490391266, 5662.943370566241, 5667.3482549507335, 5687.943120568907, 4954.30639710448, 5074.517920797518, 5513.61404701667, 5537.1140617501005, 5546.500814926676, 4324.4162038125705, 5107.6169287302855, 5240.2663627093, 5566.888662226814, 5575.247341608993, 5375.731213439391, 5376.327959005124, 5386.111291636836, 5419.972900135474, 5420.539254163283, 4879.853604391874, 5300.01949639318, 5545.445455454423, 5574.609777315712, 5584.553235741212, 5007.449180590059, 5502.404302752318, 5567.5246059795, 5575.609707320448, 5592.440755924375], "value": 4110.1943467535675, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [738.841149152932, 748.6032402826504, 786.4494853602478, 818.3575892924052, 822.7079386817676, 13091.279979601119, 15614.609634759137, 16250.674986500262, 16265.349386024545, 16307.83692163078, 14711.264436778172, 14986.0, 15008.024478408895, 15009.399624015026, 15010.924945375285, 699.9790006299794, 727.4253339861503, 869.8782170496136, 900.4957223954574, 901.364538000708, 891.189017993626, 907.6233363154283, 908.4640062363204, 909.790748127931, 910.1126401758299, 967.2697113679197, 967.318040781251, 968.8159249742527, 970.5292932927524, 971.9173870221005, 756.7843164698062, 946.8721722567468, 968.1093394077466, 970.2529052629507, 972.7276362618478, 714.0146597695199, 714.4962801225124, 714.5462631229207, 715.9868785668273, 717.1573401253559, 14710.33803478846, 14745.262736863115, 14766.483173088998, 14790.0, 14810.851891481163, 13936.58190254294, 13984.510542130976, 14054.367553460119, 14089.577312680636, 14103.0, 12656.936715316457, 12681.23912565249, 12703.110782245247, 12962.546310879074, 13286.734265314639, 3908.2769687607883, 4599.494055653875, 4603.240465323218, 4762.428508578958, 4766.904661906742, 109167.45416272947, 111138.44430777794, 112472.31291530633, 112719.0, 113000.43499782449, 9737.26970477217, 11092.156940532244, 11212.775744485229, 11282.94358528202, 11440.0, 22336.888315558645, 22341.106355745913, 22359.77640223577, 22378.664320035212, 22379.664305035436, 7813.492123011986, 7908.248716371935, 7952.801179970547, 8010.79973000668, 8181.468204566684, 4301.225779359683, 4304.160688665743, 4388.692791504628, 4400.647948164204, 4525.253333200051, 3443.2252743133095, 3554.4668299754726, 3559.7864128152396, 3572.4105522588484, 3572.7141828654167, 628.3025841316136, 648.1412128929188, 649.8732747114362, 663.3167837127767, 664.4451882677921, 513.278843225269, 547.3349879895945, 561.8286422641052, 616.2143267334095, 623.7130919776863, 8599.78500537479, 8854.690085846965, 9012.684556040507, 9194.448333100036, 9316.161545460942, 535.9624826262202, 569.1661715586686, 611.1932249430708, 625.9734036180668, 654.7773756922669, 4314.892127696833, 4355.912881742411, 4477.878742737984, 4616.8614941551805, 4619.353290539327, 146183.0, 146518.0, 155982.0, 157640.0, 157912.21043894708, 135876.0, 138562.30718846343, 138855.0, 140718.99999999796, 140999.2950035243, 113556.0, 117840.41079794546, 118935.0, 119000.40499797446, 119212.0, 8161.530924433659, 8499.107593702573, 8634.654613815628, 8741.606627701642, 8840.885982575472, 61921.69039154775, 63343.366566333745, 63505.364946351794, 64848.0, 65132.0, 60187.69906150441, 62245.06632400427, 98097.52853707343, 99803.0, 100453.0, 13472.461101555828, 13876.72246555043, 13904.304784760923, 14211.431542738175, 14295.07082039664, 14704.338304776526, 14831.555053348417, 15014.62463438379, 15083.170425626256, 15392.381780650207, 14003.0, 14089.718205635625, 14277.286135692966, 14285.0, 14630.561083167097, 13791.379387927367, 13806.792898106336, 14223.786643200154, 14698.191599462132, 14705.191214483306, 13555.86444135546, 13561.593152205449, 13869.167849929037, 14280.714385712436, 14311.856881431053, 9475.952620236854, 10068.899311006797, 11814.763704725685, 11869.109816763605, 12231.938840305742, 9086.409383390323, 9395.530223488662, 9686.0, 9734.659286924923, 10430.226861433423], "value": 7927.05144692252, "unit": "score"}], "name": "dromaeo_css", "value": 7497.6210376365825, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 113.0, 112.0, 117.0, 113.0, 115.0, 114.0, 113.0, 116.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 108.0, 106.0, 158.0, 109.0, 108.0, 132.0, 107.0, 139.0, 109.0], "value": 122.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 181.0, 181.0, 184.0, 183.0, 184.0, 183.0, 186.0, 182.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 59.0, 85.0, 68.0, 56.0, 62.0, 64.0, 99.0, 55.0], "value": 69.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 92.0, 72.0, 71.0, 69.0, 95.0, 69.0, 68.0, 101.0], "value": 80.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 88.0, 85.0, 87.0, 85.0, 87.0, 87.0, 91.0, 83.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 101.0, 93.0, 93.0, 93.0, 94.0, 93.0, 93.0, 92.0], "value": 93.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 85.0, 102.0, 139.0, 105.0, 84.0, 86.0, 85.0, 84.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 53.0, 55.0, 53.0, 55.0, 56.0, 54.0, 54.0, 53.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 77.0, 78.0, 73.0, 71.0, 72.0, 72.0, 71.0, 73.0], "value": 72.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 99.0, 85.0, 80.0, 82.0, 83.0, 84.0, 86.0, 94.0, 92.0], "value": 86.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 134.0, 140.0, 148.0, 150.0, 148.0, 149.0, 137.0, 184.0, 138.0], "value": 146.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 164.0, 162.0, 162.0, 164.0, 164.0, 164.0, 162.0, 164.0], "value": 163.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 70.0, 77.0, 86.0, 84.0, 83.0, 85.0, 67.0, 66.0], "value": 75.0, "unit": "score"}], "name": "kraken", "value": 1441.6, "alertThreshold": 2.0}]} 10:04:47 INFO - Return code: 0 10:04:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:04:47 INFO - # TBPL SUCCESS # 10:04:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:04:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:04:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:04:47 INFO - 2016-07-12 10:04:47.227 screenresolution[1669:9663] starting screenresolution argv=screenresolution get 10:04:47 INFO - 2016-07-12 10:04:47.230 screenresolution[1669:9663] Display 0: 1600x1200x32@60 10:04:47 INFO - 2016-07-12 10:04:47.236 screenresolution[1670:9666] starting screenresolution argv=screenresolution list 10:04:47 INFO - Available Modes on Display 0 10:04:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:04:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:04:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:04:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:04:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:04:47 INFO - Intel Iris: 10:04:47 INFO - Chipset Model: Intel Iris 10:04:47 INFO - Type: GPU 10:04:47 INFO - Bus: Built-In 10:04:47 INFO - VRAM (Dynamic, Max): 1536 MB 10:04:47 INFO - Vendor: Intel (0x8086) 10:04:47 INFO - Device ID: 0x0a2e 10:04:47 INFO - Revision ID: 0x0009 10:04:47 INFO - Displays: 10:04:47 INFO - Display: 10:04:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:04:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:04:47 INFO - Display Serial Number: 1600x1200 60 10:04:47 INFO - Main Display: Yes 10:04:47 INFO - Mirror: Off 10:04:47 INFO - Online: Yes 10:04:47 INFO - Rotation: Supported 10:04:47 INFO - Return code: 0 10:04:47 INFO - Running post-action listener: _resource_record_post_action 10:04:47 INFO - ##### 10:04:47 INFO - ##### Finished run-tests step (success) 10:04:47 INFO - ##### 10:04:47 INFO - Running post-run listener: _resource_record_post_run 10:04:47 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 38213120; Write bytes: 703560704; Read time: 381; Write time: 2004 10:04:47 INFO - TinderboxPrint: CPU usage
53.6% 10:04:47 INFO - TinderboxPrint: I/O read bytes / time
38,213,120 / 381 10:04:47 INFO - TinderboxPrint: I/O write bytes / time
703,560,704 / 2,004 10:04:47 INFO - TinderboxPrint: CPU idle
2,246.2 (46.4%) 10:04:47 INFO - TinderboxPrint: CPU system
967.0 (20.0%) 10:04:47 INFO - TinderboxPrint: CPU user
1,628.4 (33.6%) 10:04:47 INFO - TinderboxPrint: Swap in / out
444,588,032 / 0 10:04:47 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 402340352; Write bytes: 394196992; Read time: 29678; Write time: 704 10:04:47 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 36103680; Write bytes: 293610496; Read time: 356; Write time: 1260 10:04:47 INFO - Running post-run listener: _upload_blobber_files 10:04:47 INFO - Blob upload gear active. 10:04:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:04:47 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 10:04:47 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'] 10:04:47 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 10:04:48 INFO - (blobuploader) - INFO - Open directory for files ... 10:04:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:04:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:04:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:04:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:04:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:04:49 INFO - (blobuploader) - INFO - Done attempting. 10:04:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:04:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:04:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:04:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:04:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:04:50 INFO - (blobuploader) - INFO - Done attempting. 10:04:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:04:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:04:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:04:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:04:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:04:52 INFO - (blobuploader) - INFO - Done attempting. 10:04:52 INFO - (blobuploader) - INFO - Iteration through files over. 10:04:52 INFO - Return code: 0 10:04:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:04:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:04:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9617ab93fbf9193b195b1cad32a3bd6d1556a0cadad5b500a028e5bcbf27601d0cb5794adf1093915c167d5a4d8c442aa5331a001f898f3ec9d45ac278c6196f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c9092a76ee41537c514314b49e2ae0c8dc35109a6f396b0b30e3ff961b0f5eb334ab7fccc095659c9d7df9d4240feb0d77416051ae8eab2e5c9239ed38e6ab2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b48f4dc8adf7953047335c1116a021040bc71966ceda9b9f705793b0fcc56db89620801bfef917e9f5572e75d8c8e4234c5f3b64993b75b411c2344ffa0f9d1b"} 10:04:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:04:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:04:52 INFO - Contents: 10:04:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9617ab93fbf9193b195b1cad32a3bd6d1556a0cadad5b500a028e5bcbf27601d0cb5794adf1093915c167d5a4d8c442aa5331a001f898f3ec9d45ac278c6196f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c9092a76ee41537c514314b49e2ae0c8dc35109a6f396b0b30e3ff961b0f5eb334ab7fccc095659c9d7df9d4240feb0d77416051ae8eab2e5c9239ed38e6ab2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b48f4dc8adf7953047335c1116a021040bc71966ceda9b9f705793b0fcc56db89620801bfef917e9f5572e75d8c8e4234c5f3b64993b75b411c2344ffa0f9d1b"} 10:04:52 INFO - Running post-run listener: copy_logs_to_upload_dir 10:04:52 INFO - Copying logs to upload dir... 10:04:52 INFO - mkdir: /builds/slave/test/build/upload/logs 10:04:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.497703 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-07-12 10:04:52.957786) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 10:04:52.961759) ========= 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.f4zkqW1iJz/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.7iiU39Qqg1/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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/9617ab93fbf9193b195b1cad32a3bd6d1556a0cadad5b500a028e5bcbf27601d0cb5794adf1093915c167d5a4d8c442aa5331a001f898f3ec9d45ac278c6196f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c9092a76ee41537c514314b49e2ae0c8dc35109a6f396b0b30e3ff961b0f5eb334ab7fccc095659c9d7df9d4240feb0d77416051ae8eab2e5c9239ed38e6ab2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b48f4dc8adf7953047335c1116a021040bc71966ceda9b9f705793b0fcc56db89620801bfef917e9f5572e75d8c8e4234c5f3b64993b75b411c2344ffa0f9d1b"} build_url:https://queue.taskcluster.net/v1/task/RVcsBBmuTFaRFZDI31It6w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011007 build_url: 'https://queue.taskcluster.net/v1/task/RVcsBBmuTFaRFZDI31It6w/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/9617ab93fbf9193b195b1cad32a3bd6d1556a0cadad5b500a028e5bcbf27601d0cb5794adf1093915c167d5a4d8c442aa5331a001f898f3ec9d45ac278c6196f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c9092a76ee41537c514314b49e2ae0c8dc35109a6f396b0b30e3ff961b0f5eb334ab7fccc095659c9d7df9d4240feb0d77416051ae8eab2e5c9239ed38e6ab2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b48f4dc8adf7953047335c1116a021040bc71966ceda9b9f705793b0fcc56db89620801bfef917e9f5572e75d8c8e4234c5f3b64993b75b411c2344ffa0f9d1b"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 10:04:53.001235) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 10:04:53.001799) ========= 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.f4zkqW1iJz/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.7iiU39Qqg1/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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.005109 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 10:04:53.051408) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 10:04:53.051723) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 10:04:53.052081) ========= ========= Total master_lag: 0.18 =========