builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0289 starttime: 1468605129.88 results: success (0) buildid: 20160715071456 builduid: 2def54cf46424ce78440e660d78fc895 revision: 683bf5d3c1cdd76799d7da01d3a0cd2dbb42e8f9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 10:52:09.879247) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 10:52:09.879696) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 10:52:09.879984) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 10:52:10.012845) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 10:52:10.013173) ========= 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.UccTJiasXS/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.Nwgj9pa0m2/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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-15 10:52:10-- 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% 14.3M=0.001s 2016-07-15 10:52:10 (14.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.462040 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 10:52:10.503990) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 10:52:10.504388) ========= 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.UccTJiasXS/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.Nwgj9pa0m2/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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.025687 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 10:52:10.552412) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-15 10:52:10.552709) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 683bf5d3c1cdd76799d7da01d3a0cd2dbb42e8f9 --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 683bf5d3c1cdd76799d7da01d3a0cd2dbb42e8f9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UccTJiasXS/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.Nwgj9pa0m2/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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-15 10:52:10,636 truncating revision to first 12 chars 2016-07-15 10:52:10,636 Setting DEBUG logging. 2016-07-15 10:52:10,636 attempt 1/10 2016-07-15 10:52:10,636 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/683bf5d3c1cd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-15 10:52:11,366 unpacking tar archive at: mozilla-central-683bf5d3c1cd/testing/mozharness/ program finished with exit code 0 elapsedTime=0.990150 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-15 10:52:11.564374) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 10:52:11.564689) ========= script_repo_revision: 683bf5d3c1cdd76799d7da01d3a0cd2dbb42e8f9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 10:52:11.565081) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 10:52:11.565339) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 10:52:11.583662) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-07-15 10:52:11.584029) ========= /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.UccTJiasXS/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.Nwgj9pa0m2/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 10:52:11 INFO - MultiFileLogger online at 20160715 10:52:11 in /builds/slave/test 10:52:11 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 10:52:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:52:11 INFO - {'append_to_log': False, 10:52:11 INFO - 'base_work_dir': '/builds/slave/test', 10:52:11 INFO - 'blob_upload_branch': 'Firefox', 10:52:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:52:11 INFO - 'branch': 'Firefox', 10:52:11 INFO - 'buildbot_json_path': 'buildprops.json', 10:52:11 INFO - 'config_files': ('talos/mac_config.py',), 10:52:11 INFO - 'default_actions': ('clobber', 10:52:11 INFO - 'read-buildbot-config', 10:52:11 INFO - 'download-and-extract', 10:52:11 INFO - 'populate-webroot', 10:52:11 INFO - 'create-virtualenv', 10:52:11 INFO - 'install', 10:52:11 INFO - 'run-tests'), 10:52:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:52:11 INFO - 'download_minidump_stackwalk': True, 10:52:11 INFO - 'download_symbols': 'ondemand', 10:52:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:52:11 INFO - 'tooltool.py': '/tools/tooltool.py', 10:52:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:52:11 INFO - '/tools/misc-python/virtualenv.py')}, 10:52:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:52:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:52:11 INFO - 'installer_path': 'installer.exe', 10:52:11 INFO - 'log_level': 'info', 10:52:11 INFO - 'log_name': 'talos', 10:52:11 INFO - 'log_to_console': True, 10:52:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:52:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:52:11 INFO - 'opt_config_files': (), 10:52:11 INFO - 'pip_index': False, 10:52:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:52:11 INFO - 'cmd': ('bash', 10:52:11 INFO - '-c', 10:52:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:52:11 INFO - 'enabled': True, 10:52:11 INFO - 'halt_on_failure': False, 10:52:11 INFO - 'name': 'check_screen_resolution'},), 10:52:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:52:11 INFO - 'cmd': ('bash', 10:52:11 INFO - '-c', 10:52:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:52:11 INFO - 'enabled': True, 10:52:11 INFO - 'halt_on_failure': False, 10:52:11 INFO - 'name': 'check_screen_resolution'},), 10:52:11 INFO - 'run_cmd_checks_enabled': True, 10:52:11 INFO - 'sps_profile': False, 10:52:11 INFO - 'sps_profile_interval': 0, 10:52:11 INFO - 'suite': 'dromaeojs', 10:52:11 INFO - 'system_bits': '32', 10:52:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:52:11 INFO - 'title': 't-yosemite-r7-0289', 10:52:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:52:11 INFO - 'use_talos_json': True, 10:52:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:52:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:52:11 INFO - 'work_dir': 'build'} 10:52:11 INFO - ##### 10:52:11 INFO - ##### Running clobber step. 10:52:11 INFO - ##### 10:52:11 INFO - Running pre-action listener: _resource_record_pre_action 10:52:11 INFO - Running main action method: clobber 10:52:11 INFO - rmtree: /builds/slave/test/build 10:52:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:52:13 INFO - Running post-action listener: _resource_record_post_action 10:52:13 INFO - ##### 10:52:13 INFO - ##### Finished clobber step (success) 10:52:13 INFO - ##### 10:52:13 INFO - ##### 10:52:13 INFO - ##### Running read-buildbot-config step. 10:52:13 INFO - ##### 10:52:13 INFO - Running pre-action listener: _resource_record_pre_action 10:52:13 INFO - Running main action method: read_buildbot_config 10:52:13 INFO - Using buildbot properties: 10:52:13 INFO - { 10:52:13 INFO - "project": "", 10:52:13 INFO - "product": "firefox", 10:52:13 INFO - "script_repo_revision": "production", 10:52:13 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 10:52:13 INFO - "repository": "", 10:52:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 10:52:13 INFO - "buildid": "20160715071456", 10:52:13 INFO - "pgo_build": "False", 10:52:13 INFO - "basedir": "/builds/slave/test", 10:52:13 INFO - "buildnumber": 12, 10:52:13 INFO - "slavename": "t-yosemite-r7-0289", 10:52:13 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 10:52:13 INFO - "platform": "yosemite_r7", 10:52:13 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 10:52:13 INFO - "branch": "mozilla-central", 10:52:13 INFO - "repo_path": "mozilla-central", 10:52:13 INFO - "revision": "683bf5d3c1cdd76799d7da01d3a0cd2dbb42e8f9", 10:52:13 INFO - "stage_platform": "macosx64", 10:52:13 INFO - "builduid": "2def54cf46424ce78440e660d78fc895", 10:52:13 INFO - "slavebuilddir": "test" 10:52:13 INFO - } 10:52:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/VgozL2rOSqO8xALLY6zy9g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 10:52:13 INFO - Running post-action listener: _resource_record_post_action 10:52:13 INFO - ##### 10:52:13 INFO - ##### Finished read-buildbot-config step (success) 10:52:13 INFO - ##### 10:52:13 INFO - ##### 10:52:13 INFO - ##### Running download-and-extract step. 10:52:13 INFO - ##### 10:52:13 INFO - Running pre-action listener: _resource_record_pre_action 10:52:13 INFO - Running main action method: download_and_extract 10:52:13 INFO - mkdir: /builds/slave/test/build/tests 10:52:13 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')]} 10:52:13 INFO - https://queue.taskcluster.net/v1/task/VgozL2rOSqO8xALLY6zy9g/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:52:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VgozL2rOSqO8xALLY6zy9g/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 10:52:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VgozL2rOSqO8xALLY6zy9g/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 10:52:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VgozL2rOSqO8xALLY6zy9g/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 10:52:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VgozL2rOSqO8xALLY6zy9g/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 10:52:16 INFO - Downloaded 1199 bytes. 10:52:16 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 10:52:16 INFO - Using the following test package requirements: 10:52:16 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 10:52:16 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:52:16 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 10:52:16 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:52:16 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 10:52:16 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:52:16 INFO - u'jsshell-mac.zip'], 10:52:16 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:52:16 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 10:52:16 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 10:52:16 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:52:16 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 10:52:16 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:52:16 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 10:52:16 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:52:16 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 10:52:16 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:52:16 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 10:52:16 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:52:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:16 INFO - https://queue.taskcluster.net/v1/task/VgozL2rOSqO8xALLY6zy9g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:52:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VgozL2rOSqO8xALLY6zy9g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:52:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VgozL2rOSqO8xALLY6zy9g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:52:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VgozL2rOSqO8xALLY6zy9g/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 10:52:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VgozL2rOSqO8xALLY6zy9g/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 10:52:17 INFO - Downloaded 18828635 bytes. 10:52:17 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'] 10:52:17 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 10:52:21 INFO - Return code: 0 10:52:21 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 10:52: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')]} 10:52:21 INFO - https://queue.taskcluster.net/v1/task/VgozL2rOSqO8xALLY6zy9g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:52:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VgozL2rOSqO8xALLY6zy9g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:52:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VgozL2rOSqO8xALLY6zy9g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:52:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VgozL2rOSqO8xALLY6zy9g/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 10:52:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VgozL2rOSqO8xALLY6zy9g/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 10:52:23 INFO - Downloaded 18828635 bytes. 10:52:23 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'] 10:52:23 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 10:52:27 INFO - Return code: 0 10:52:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:27 INFO - https://queue.taskcluster.net/v1/task/VgozL2rOSqO8xALLY6zy9g/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:52:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VgozL2rOSqO8xALLY6zy9g/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 10:52:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VgozL2rOSqO8xALLY6zy9g/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 10:52:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VgozL2rOSqO8xALLY6zy9g/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 10:52:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VgozL2rOSqO8xALLY6zy9g/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 10:52:28 INFO - Downloaded 14403317 bytes. 10:52:28 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'] 10:52:28 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 10:52:29 INFO - Return code: 0 10:52:29 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')]} 10:52:29 INFO - https://queue.taskcluster.net/v1/task/VgozL2rOSqO8xALLY6zy9g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 10:52:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VgozL2rOSqO8xALLY6zy9g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:52:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VgozL2rOSqO8xALLY6zy9g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:52:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VgozL2rOSqO8xALLY6zy9g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 10:52:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VgozL2rOSqO8xALLY6zy9g/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 10:52:36 INFO - Downloaded 112762079 bytes. 10:52:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VgozL2rOSqO8xALLY6zy9g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:52:36 INFO - mkdir: /builds/slave/test/properties 10:52:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:52:36 INFO - Writing to file /builds/slave/test/properties/build_url 10:52:36 INFO - Contents: 10:52:36 INFO - build_url:https://queue.taskcluster.net/v1/task/VgozL2rOSqO8xALLY6zy9g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:52:38 INFO - Running post-action listener: _resource_record_post_action 10:52:38 INFO - Running post-action listener: set_extra_try_arguments 10:52:38 INFO - ##### 10:52:38 INFO - ##### Finished download-and-extract step (success) 10:52:38 INFO - ##### 10:52:38 INFO - ##### 10:52:38 INFO - ##### Running populate-webroot step. 10:52:38 INFO - ##### 10:52:38 INFO - Running pre-action listener: _resource_record_pre_action 10:52:38 INFO - Running main action method: populate_webroot 10:52:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:52:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:52:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:52:38 INFO - '--tppagecycles', 10:52:38 INFO - '7'], 10:52:38 INFO - 'tests': ['tsvgm']}}, 10:52:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:52:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:52:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:52:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:52:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:52:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:52:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:38 INFO - 'talos_options': ['--disable-e10s'], 10:52:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:52:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:52:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:38 INFO - 'talos_options': ['--disable-e10s'], 10:52:38 INFO - 'tests': ['damp', 'tps']}, 10:52:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:38 INFO - 'tests': ['damp', 'tps']}, 10:52:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:52:38 INFO - 'tests': ['dromaeo_dom']}, 10:52:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:52:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:52:38 INFO - 'tests': ['basic_compositor_video']}, 10:52:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:52:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:52:38 INFO - 'tests': ['a11yr', 10:52:38 INFO - 'ts_paint', 10:52:38 INFO - 'tpaint', 10:52:38 INFO - 'sessionrestore', 10:52:38 INFO - 'sessionrestore_no_auto_restore', 10:52:38 INFO - 'tabpaint']}, 10:52:38 INFO - 'other-e10s': {'tests': ['a11yr', 10:52:38 INFO - 'ts_paint', 10:52:38 INFO - 'tpaint', 10:52:38 INFO - 'sessionrestore', 10:52:38 INFO - 'sessionrestore_no_auto_restore', 10:52:38 INFO - 'tabpaint']}, 10:52:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:52:38 INFO - 'ts_paint', 10:52:38 INFO - 'tpaint', 10:52:38 INFO - 'sessionrestore', 10:52:38 INFO - 'sessionrestore_no_auto_restore', 10:52:38 INFO - 'tabpaint']}, 10:52:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:52:38 INFO - 'ts_paint', 10:52:38 INFO - 'tpaint', 10:52:38 INFO - 'sessionrestore', 10:52:38 INFO - 'sessionrestore_no_auto_restore', 10:52:38 INFO - 'tabpaint']}, 10:52:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:52:38 INFO - 'tests': ['a11yr', 10:52:38 INFO - 'ts_paint', 10:52:38 INFO - 'tpaint', 10:52:38 INFO - 'sessionrestore', 10:52:38 INFO - 'sessionrestore_no_auto_restore', 10:52:38 INFO - 'tabpaint']}, 10:52:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:52:38 INFO - 'tests': ['a11yr', 10:52:38 INFO - 'ts_paint', 10:52:38 INFO - 'tpaint', 10:52:38 INFO - 'sessionrestore', 10:52:38 INFO - 'sessionrestore_no_auto_restore', 10:52:38 INFO - 'tabpaint']}, 10:52:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:52:38 INFO - 'tests': ['tsvgx', 10:52:38 INFO - 'tsvgr_opacity', 10:52:38 INFO - 'tart', 10:52:38 INFO - 'tscrollx', 10:52:38 INFO - 'cart']}, 10:52:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:52:38 INFO - 'tsvgr_opacity', 10:52:38 INFO - 'tart', 10:52:38 INFO - 'tscrollx', 10:52:38 INFO - 'cart']}, 10:52:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:38 INFO - 'talos_options': ['--disable-e10s'], 10:52:38 INFO - 'tests': ['tp5o']}, 10:52:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:38 INFO - 'tests': ['tp5o']}, 10:52:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:38 INFO - 'talos_options': ['--disable-e10s', 10:52:38 INFO - '--xperf_path', 10:52:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:52:38 INFO - 'tests': ['tp5n']}, 10:52:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:38 INFO - 'talos_options': ['--xperf_path', 10:52:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:52:38 INFO - 'tests': ['tp5n']}}, 10:52:38 INFO - 'talos.zip': {'path': '', 10:52:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:52:38 INFO - Running post-action listener: _resource_record_post_action 10:52:38 INFO - ##### 10:52:38 INFO - ##### Finished populate-webroot step (success) 10:52:38 INFO - ##### 10:52:38 INFO - ##### 10:52:38 INFO - ##### Running create-virtualenv step. 10:52:38 INFO - ##### 10:52:38 INFO - Running pre-action listener: _resource_record_pre_action 10:52:38 INFO - Running main action method: create_virtualenv 10:52:38 INFO - Creating virtualenv /builds/slave/test/build/venv 10:52:38 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 10:52:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:52:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:52:38 INFO - Using real prefix '/tools/python27' 10:52:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:52:39 INFO - Installing distribute.............................................................................................................................................................................................done. 10:52:41 INFO - Installing pip.................done. 10:52:41 INFO - Return code: 0 10:52:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:52:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110081a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110116618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb481ca30c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffe5d30>, '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 0x10fd4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ffddb20>, '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.UccTJiasXS/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.Nwgj9pa0m2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 10:52:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:52:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:52:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UccTJiasXS/Render', 10:52:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:41 INFO - 'HOME': '/Users/cltbld', 10:52:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:41 INFO - 'LOGNAME': 'cltbld', 10:52:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:41 INFO - 'MOZ_NO_REMOTE': '1', 10:52:41 INFO - 'NO_EM_RESTART': '1', 10:52:41 INFO - 'PAGER': '/bin/cat', 10:52:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:41 INFO - 'PWD': '/builds/slave/test', 10:52:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:41 INFO - 'SHELL': '/bin/bash', 10:52:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Nwgj9pa0m2/Listeners', 10:52:41 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 10:52:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:41 INFO - 'USER': 'cltbld', 10:52:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:41 INFO - 'XPC_FLAGS': '0x0', 10:52:41 INFO - 'XPC_SERVICE_NAME': '0', 10:52:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:42 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 10:52:42 INFO - Cleaning up... 10:52:42 INFO - Return code: 0 10:52:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:52:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x110081a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110116618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb481ca30c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffe5d30>, '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 0x10fd4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ffddb20>, '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.UccTJiasXS/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.Nwgj9pa0m2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 10:52:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:52:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:52:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UccTJiasXS/Render', 10:52:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:42 INFO - 'HOME': '/Users/cltbld', 10:52:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:42 INFO - 'LOGNAME': 'cltbld', 10:52:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:42 INFO - 'MOZ_NO_REMOTE': '1', 10:52:42 INFO - 'NO_EM_RESTART': '1', 10:52:42 INFO - 'PAGER': '/bin/cat', 10:52:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:42 INFO - 'PWD': '/builds/slave/test', 10:52:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:42 INFO - 'SHELL': '/bin/bash', 10:52:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Nwgj9pa0m2/Listeners', 10:52:42 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 10:52:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:42 INFO - 'USER': 'cltbld', 10:52:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:42 INFO - 'XPC_FLAGS': '0x0', 10:52:42 INFO - 'XPC_SERVICE_NAME': '0', 10:52:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:42 INFO - Downloading/unpacking psutil>=3.1.1 10:52:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:52:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:52:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:52:45 INFO - Installing collected packages: psutil 10:52:45 INFO - Running setup.py install for psutil 10:52:45 INFO - building 'psutil._psutil_osx' extension 10:52:45 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 10:52:46 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 10:52:46 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 10:52:46 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 10:52:46 INFO - building 'psutil._psutil_posix' extension 10:52:46 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 10:52:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:52:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:52:46 INFO - ^ 10:52:46 INFO - 1 warning generated. 10:52:46 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 10:52:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:52:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:52:46 INFO - Successfully installed psutil 10:52:46 INFO - Cleaning up... 10:52:46 INFO - Return code: 0 10:52:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:52:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110081a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110116618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb481ca30c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffe5d30>, '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 0x10fd4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ffddb20>, '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.UccTJiasXS/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.Nwgj9pa0m2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 10:52:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:52:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:52:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UccTJiasXS/Render', 10:52:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:46 INFO - 'HOME': '/Users/cltbld', 10:52:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:46 INFO - 'LOGNAME': 'cltbld', 10:52:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:46 INFO - 'MOZ_NO_REMOTE': '1', 10:52:46 INFO - 'NO_EM_RESTART': '1', 10:52:46 INFO - 'PAGER': '/bin/cat', 10:52:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:46 INFO - 'PWD': '/builds/slave/test', 10:52:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:46 INFO - 'SHELL': '/bin/bash', 10:52:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Nwgj9pa0m2/Listeners', 10:52:46 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 10:52:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:46 INFO - 'USER': 'cltbld', 10:52:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:46 INFO - 'XPC_FLAGS': '0x0', 10:52:46 INFO - 'XPC_SERVICE_NAME': '0', 10:52:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:52:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:52:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:52:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:52:49 INFO - Installing collected packages: mozsystemmonitor 10:52:49 INFO - Running setup.py install for mozsystemmonitor 10:52:49 INFO - Successfully installed mozsystemmonitor 10:52:49 INFO - Cleaning up... 10:52:49 INFO - Return code: 0 10:52:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:52:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x110081a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110116618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb481ca30c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffe5d30>, '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 0x10fd4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ffddb20>, '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.UccTJiasXS/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.Nwgj9pa0m2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 10:52:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:52:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:52:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UccTJiasXS/Render', 10:52:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:49 INFO - 'HOME': '/Users/cltbld', 10:52:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:49 INFO - 'LOGNAME': 'cltbld', 10:52:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:49 INFO - 'MOZ_NO_REMOTE': '1', 10:52:49 INFO - 'NO_EM_RESTART': '1', 10:52:49 INFO - 'PAGER': '/bin/cat', 10:52:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:49 INFO - 'PWD': '/builds/slave/test', 10:52:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:49 INFO - 'SHELL': '/bin/bash', 10:52:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Nwgj9pa0m2/Listeners', 10:52:49 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 10:52:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:49 INFO - 'USER': 'cltbld', 10:52:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:49 INFO - 'XPC_FLAGS': '0x0', 10:52:49 INFO - 'XPC_SERVICE_NAME': '0', 10:52:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:50 INFO - Downloading/unpacking jsonschema==2.5.1 10:52:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:52:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:52:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:53 INFO - Downloading functools32-3.2.3-2.tar.gz 10:52:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:52:53 INFO - warning: no files found matching '*.txt' 10:52:53 INFO - no previously-included directories found matching 'build' 10:52:53 INFO - no previously-included directories found matching 'dist' 10:52:53 INFO - no previously-included directories found matching '.git*' 10:52:53 INFO - Installing collected packages: jsonschema, functools32 10:52:53 INFO - Running setup.py install for functools32 10:52:53 INFO - warning: no files found matching '*.txt' 10:52:53 INFO - no previously-included directories found matching 'build' 10:52:53 INFO - no previously-included directories found matching 'dist' 10:52:53 INFO - no previously-included directories found matching '.git*' 10:52:53 INFO - Successfully installed jsonschema functools32 10:52:53 INFO - Cleaning up... 10:52:53 INFO - Return code: 0 10:52:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:52:53 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')]} 10:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:53 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')]} 10:52:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:53 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 0x110081a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110116618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb481ca30c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffe5d30>, '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 0x10fd4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ffddb20>, '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.UccTJiasXS/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.Nwgj9pa0m2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 10:52:53 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 10:52:53 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 10:52:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UccTJiasXS/Render', 10:52:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:53 INFO - 'HOME': '/Users/cltbld', 10:52:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:53 INFO - 'LOGNAME': 'cltbld', 10:52:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:53 INFO - 'MOZ_NO_REMOTE': '1', 10:52:53 INFO - 'NO_EM_RESTART': '1', 10:52:53 INFO - 'PAGER': '/bin/cat', 10:52:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:53 INFO - 'PWD': '/builds/slave/test', 10:52:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:53 INFO - 'SHELL': '/bin/bash', 10:52:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Nwgj9pa0m2/Listeners', 10:52:53 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 10:52:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:53 INFO - 'USER': 'cltbld', 10:52:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:53 INFO - 'XPC_FLAGS': '0x0', 10:52:53 INFO - 'XPC_SERVICE_NAME': '0', 10:52:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:53 INFO - Downloading/unpacking blobuploader==1.2.4 10:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:56 INFO - Downloading blobuploader-1.2.4.tar.gz 10:52:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:52:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:52:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:57 INFO - Downloading docopt-0.6.1.tar.gz 10:52:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:52:57 INFO - Installing collected packages: blobuploader, requests, docopt 10:52:57 INFO - Running setup.py install for blobuploader 10:52:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:52:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:52:57 INFO - Running setup.py install for requests 10:52:58 INFO - Running setup.py install for docopt 10:52:58 INFO - Successfully installed blobuploader requests docopt 10:52:58 INFO - Cleaning up... 10:52:58 INFO - Return code: 0 10:52:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:52:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:58 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 0x110081a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110116618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb481ca30c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffe5d30>, '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 0x10fd4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ffddb20>, '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.UccTJiasXS/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.Nwgj9pa0m2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 10:52:58 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 10:52:58 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 10:52:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UccTJiasXS/Render', 10:52:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:58 INFO - 'HOME': '/Users/cltbld', 10:52:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:58 INFO - 'LOGNAME': 'cltbld', 10:52:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:58 INFO - 'MOZ_NO_REMOTE': '1', 10:52:58 INFO - 'NO_EM_RESTART': '1', 10:52:58 INFO - 'PAGER': '/bin/cat', 10:52:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:58 INFO - 'PWD': '/builds/slave/test', 10:52:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:58 INFO - 'SHELL': '/bin/bash', 10:52:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Nwgj9pa0m2/Listeners', 10:52:58 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 10:52:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:58 INFO - 'USER': 'cltbld', 10:52:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:58 INFO - 'XPC_FLAGS': '0x0', 10:52:58 INFO - 'XPC_SERVICE_NAME': '0', 10:52:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:52:58 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-MEbBfa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:52:58 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-mLfsnp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:52:58 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-zjNWMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:52:58 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-CNYcVb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:52:58 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-t6PLN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:52:59 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-uSOPLv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:52:59 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-DmKr_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:52:59 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-75U9JE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:52:59 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-EnjF3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:52:59 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-rzI3uw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:52:59 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-_b1J8i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:52:59 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-8P0QrW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:52:59 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-bRHryt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:52:59 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-Tw1e7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:53:00 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-6sZP3n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:53:00 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-5pSs8E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:53:00 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-Dwc8z0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:53:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:53:00 INFO - Running setup.py install for manifestparser 10:53:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:53:00 INFO - Running setup.py install for mozcrash 10:53:00 INFO - Running setup.py install for mozdebug 10:53:00 INFO - Running setup.py install for mozdevice 10:53:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:53:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:53:00 INFO - Running setup.py install for mozfile 10:53:01 INFO - Running setup.py install for mozhttpd 10:53:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:53:01 INFO - Running setup.py install for mozinfo 10:53:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:53:01 INFO - Running setup.py install for mozInstall 10:53:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:53:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:53:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:53:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:53:01 INFO - Running setup.py install for mozleak 10:53:01 INFO - Running setup.py install for mozlog 10:53:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:53:01 INFO - Running setup.py install for moznetwork 10:53:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:53:01 INFO - Running setup.py install for mozprocess 10:53:02 INFO - Running setup.py install for mozprofile 10:53:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:53:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:53:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:53:02 INFO - Running setup.py install for mozrunner 10:53:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:53:02 INFO - Running setup.py install for mozscreenshot 10:53:02 INFO - Running setup.py install for moztest 10:53:02 INFO - Running setup.py install for mozversion 10:53:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:53:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:53:02 INFO - Cleaning up... 10:53:02 INFO - Return code: 0 10:53:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:53: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')]} 10:53:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53: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')]} 10:53:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:02 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 0x110081a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110116618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb481ca30c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffe5d30>, '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 0x10fd4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ffddb20>, '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.UccTJiasXS/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.Nwgj9pa0m2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 10:53:02 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 10:53:02 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 10:53:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UccTJiasXS/Render', 10:53:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:02 INFO - 'HOME': '/Users/cltbld', 10:53:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:02 INFO - 'LOGNAME': 'cltbld', 10:53:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:02 INFO - 'MOZ_NO_REMOTE': '1', 10:53:02 INFO - 'NO_EM_RESTART': '1', 10:53:02 INFO - 'PAGER': '/bin/cat', 10:53:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:02 INFO - 'PWD': '/builds/slave/test', 10:53:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:02 INFO - 'SHELL': '/bin/bash', 10:53:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Nwgj9pa0m2/Listeners', 10:53:02 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 10:53:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:02 INFO - 'USER': 'cltbld', 10:53:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:02 INFO - 'XPC_FLAGS': '0x0', 10:53:02 INFO - 'XPC_SERVICE_NAME': '0', 10:53:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:53:03 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-88n8FH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:53:03 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)) 10:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:53:03 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-wW0lSR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:53:03 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)) 10:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:53:03 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-OUucyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:53:03 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)) 10:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:53:03 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-xv0iyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:53:03 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)) 10:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:53:03 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-sjoZTe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:53:03 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)) 10:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:53:03 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-am0WJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:53:03 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)) 10:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:53:03 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-VSY8sG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:53:03 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)) 10:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:53:03 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-mueTrC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:53:04 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)) 10:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:53:04 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-TtIqWY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:53:04 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)) 10:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:53:04 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-NEZGIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:53:04 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)) 10:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:53:04 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-fss82Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:53:04 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)) 10:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:53:04 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-6qu3FZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:53:04 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)) 10:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:53:04 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-k4jVse-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:53:04 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)) 10:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:53:04 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-0RXeUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:53:04 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)) 10:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:53:04 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-71LJqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:53:04 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)) 10:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:53:04 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-OL7qlk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:53:04 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)) 10:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:53:04 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-aNL8ml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:53:04 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)) 10:53:04 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)) 10:53:04 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)) 10:53:04 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)) 10:53:04 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)) 10:53:04 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)) 10:53:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:53:04 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 10:53:04 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 10:53:04 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 10:53:04 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 10:53:04 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 10:53:04 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 10:53:07 INFO - Downloading blessings-1.6.tar.gz 10:53:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:53:07 INFO - Installing collected packages: blessings 10:53:07 INFO - Running setup.py install for blessings 10:53:07 INFO - Successfully installed blessings 10:53:07 INFO - Cleaning up... 10:53:07 INFO - Return code: 0 10:53:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:53:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:53:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:53:08 INFO - Reading from file tmpfile_stdout 10:53:08 INFO - Current package versions: 10:53:08 INFO - blessings == 1.6 10:53:08 INFO - blobuploader == 1.2.4 10:53:08 INFO - docopt == 0.6.1 10:53:08 INFO - functools32 == 3.2.3-2 10:53:08 INFO - jsonschema == 2.5.1 10:53:08 INFO - manifestparser == 1.1 10:53:08 INFO - mozInstall == 1.12 10:53:08 INFO - mozcrash == 0.17 10:53:08 INFO - mozdebug == 0.1 10:53:08 INFO - mozdevice == 0.48 10:53:08 INFO - mozfile == 1.2 10:53:08 INFO - mozhttpd == 0.7 10:53:08 INFO - mozinfo == 0.9 10:53:08 INFO - mozleak == 0.1 10:53:08 INFO - mozlog == 3.2 10:53:08 INFO - moznetwork == 0.27 10:53:08 INFO - mozprocess == 0.23 10:53:08 INFO - mozprofile == 0.28 10:53:08 INFO - mozrunner == 6.12 10:53:08 INFO - mozscreenshot == 0.1 10:53:08 INFO - mozsystemmonitor == 0.3 10:53:08 INFO - moztest == 0.7 10:53:08 INFO - mozversion == 1.4 10:53:08 INFO - psutil == 3.1.1 10:53:08 INFO - requests == 1.2.3 10:53:08 INFO - wsgiref == 0.1.2 10:53:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:53:08 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')]} 10:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:08 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')]} 10:53:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:08 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 0x110081a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110116618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb481ca30c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffe5d30>, '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 0x10fd4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ffddb20>, '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.UccTJiasXS/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.Nwgj9pa0m2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 10:53:08 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 10:53:08 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 10:53:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UccTJiasXS/Render', 10:53:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:08 INFO - 'HOME': '/Users/cltbld', 10:53:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:08 INFO - 'LOGNAME': 'cltbld', 10:53:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:08 INFO - 'MOZ_NO_REMOTE': '1', 10:53:08 INFO - 'NO_EM_RESTART': '1', 10:53:08 INFO - 'PAGER': '/bin/cat', 10:53:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:08 INFO - 'PWD': '/builds/slave/test', 10:53:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:08 INFO - 'SHELL': '/bin/bash', 10:53:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Nwgj9pa0m2/Listeners', 10:53:08 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 10:53:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:08 INFO - 'USER': 'cltbld', 10:53:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:08 INFO - 'XPC_FLAGS': '0x0', 10:53:08 INFO - 'XPC_SERVICE_NAME': '0', 10:53:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:08 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)) 10:53:08 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)) 10:53:08 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)) 10:53:08 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)) 10:53:08 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)) 10:53:08 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)) 10:53:08 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)) 10:53:08 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)) 10:53:08 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)) 10:53:08 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)) 10:53:08 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)) 10:53:08 INFO - Cleaning up... 10:53:08 INFO - Return code: 0 10:53:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:53:08 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')]} 10:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:08 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')]} 10:53:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:08 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 0x110081a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110116618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb481ca30c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffe5d30>, '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 0x10fd4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ffddb20>, '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.UccTJiasXS/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.Nwgj9pa0m2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 10:53:08 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 10:53:08 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 10:53:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UccTJiasXS/Render', 10:53:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:08 INFO - 'HOME': '/Users/cltbld', 10:53:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:08 INFO - 'LOGNAME': 'cltbld', 10:53:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:08 INFO - 'MOZ_NO_REMOTE': '1', 10:53:08 INFO - 'NO_EM_RESTART': '1', 10:53:08 INFO - 'PAGER': '/bin/cat', 10:53:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:08 INFO - 'PWD': '/builds/slave/test', 10:53:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:08 INFO - 'SHELL': '/bin/bash', 10:53:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Nwgj9pa0m2/Listeners', 10:53:08 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 10:53:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:08 INFO - 'USER': 'cltbld', 10:53:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:08 INFO - 'XPC_FLAGS': '0x0', 10:53:08 INFO - 'XPC_SERVICE_NAME': '0', 10:53:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:53:08 INFO - Cleaning up... 10:53:08 INFO - Return code: 0 10:53:08 INFO - Running post-action listener: _resource_record_post_action 10:53:08 INFO - Running post-action listener: _start_resource_monitoring 10:53:08 INFO - Starting resource monitoring. 10:53:08 INFO - ##### 10:53:08 INFO - ##### Finished create-virtualenv step (success) 10:53:08 INFO - ##### 10:53:08 INFO - ##### 10:53:08 INFO - ##### Running install step. 10:53:08 INFO - ##### 10:53:08 INFO - Running pre-action listener: _resource_record_pre_action 10:53:08 INFO - Running main action method: install 10:53:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:53:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:53:08 INFO - Reading from file tmpfile_stdout 10:53:08 INFO - Detecting whether we're running mozinstall >=1.0... 10:53:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:53:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:53:09 INFO - Reading from file tmpfile_stdout 10:53:09 INFO - Output received: 10:53:09 INFO - Usage: mozinstall [options] installer 10:53:09 INFO - Options: 10:53:09 INFO - -h, --help show this help message and exit 10:53:09 INFO - -d DEST, --destination=DEST 10:53:09 INFO - Directory to install application into. [default: 10:53:09 INFO - "/builds/slave/test"] 10:53:09 INFO - --app=APP Application being installed. [default: firefox] 10:53:09 INFO - mkdir: /builds/slave/test/build/application 10:53:09 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'] 10:53:09 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 10:53:43 INFO - Reading from file tmpfile_stdout 10:53:43 INFO - Output received: 10:53:43 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:53:43 INFO - Running post-action listener: _resource_record_post_action 10:53:43 INFO - ##### 10:53:43 INFO - ##### Finished install step (success) 10:53:43 INFO - ##### 10:53:43 INFO - ##### 10:53:43 INFO - ##### Running run-tests step. 10:53:43 INFO - ##### 10:53:43 INFO - Running pre-action listener: _resource_record_pre_action 10:53:43 INFO - Running main action method: run_tests 10:53:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:53:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:53:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:53:43 INFO - 2016-07-15 10:53:43.062 screenresolution[1662:13900] starting screenresolution argv=screenresolution get 10:53:43 INFO - 2016-07-15 10:53:43.065 screenresolution[1662:13900] Display 0: 1600x1200x32@60 10:53:43 INFO - 2016-07-15 10:53:43.071 screenresolution[1663:13902] starting screenresolution argv=screenresolution list 10:53:43 INFO - Available Modes on Display 0 10:53:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:53:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:53:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:53:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:53:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:53:43 INFO - Intel Iris: 10:53:43 INFO - Chipset Model: Intel Iris 10:53:43 INFO - Type: GPU 10:53:43 INFO - Bus: Built-In 10:53:43 INFO - VRAM (Dynamic, Max): 1536 MB 10:53:43 INFO - Vendor: Intel (0x8086) 10:53:43 INFO - Device ID: 0x0a2e 10:53:43 INFO - Revision ID: 0x0009 10:53:43 INFO - Displays: 10:53:43 INFO - Display: 10:53:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:53:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:53:43 INFO - Display Serial Number: 1600x1200 60 10:53:43 INFO - Main Display: Yes 10:53:43 INFO - Mirror: Off 10:53:43 INFO - Online: Yes 10:53:43 INFO - Rotation: Supported 10:53:43 INFO - Return code: 0 10:53:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:53:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:53:43 INFO - Python 2.7.3 10:53:43 INFO - Return code: 0 10:53:43 INFO - grabbing minidump binary from tooltool 10:53:43 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')]} 10:53:43 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 0x10ffe5d30>, '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 0x10fd4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ffddb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:53:43 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 10:53:43 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 10:53:43 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 10:53:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:53:43 INFO - Return code: 0 10:53:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:53:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:53:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:53:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:53:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:53:43 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/VgozL2rOSqO8xALLY6zy9g/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0289', '--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 10:53:43 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/VgozL2rOSqO8xALLY6zy9g/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0289 --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 10:53:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UccTJiasXS/Render', 10:53:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:43 INFO - 'HOME': '/Users/cltbld', 10:53:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:43 INFO - 'LOGNAME': 'cltbld', 10:53:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:53:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:53:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:43 INFO - 'MOZ_NO_REMOTE': '1', 10:53:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:53:43 INFO - 'NO_EM_RESTART': '1', 10:53:43 INFO - 'PAGER': '/bin/cat', 10:53:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:43 INFO - 'PWD': '/builds/slave/test', 10:53:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:53:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:43 INFO - 'SHELL': '/bin/bash', 10:53:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Nwgj9pa0m2/Listeners', 10:53:43 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 10:53:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:43 INFO - 'USER': 'cltbld', 10:53:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:43 INFO - 'XPC_FLAGS': '0x0', 10:53:43 INFO - 'XPC_SERVICE_NAME': '0', 10:53:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:43 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/VgozL2rOSqO8xALLY6zy9g/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0289', '--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 10:53:43 INFO - mozversion application_buildid: 20160715071456 10:53:43 INFO - mozversion application_changeset: 683bf5d3c1cdd76799d7da01d3a0cd2dbb42e8f9 10:53:43 INFO - mozversion application_display_name: Nightly 10:53:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:53:43 INFO - mozversion application_name: Firefox 10:53:43 INFO - mozversion application_remotingname: firefox 10:53:43 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:53:43 INFO - mozversion application_vendor: Mozilla 10:53:43 INFO - mozversion application_version: 50.0a1 10:53:43 INFO - mozversion platform_buildid: 20160715071456 10:53:43 INFO - mozversion platform_changeset: 683bf5d3c1cdd76799d7da01d3a0cd2dbb42e8f9 10:53:43 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:53:43 INFO - mozversion platform_version: 50.0a1 10:53:43 INFO - using testdate: 1468605223 10:53:43 INFO - actual date: 1468605223 10:53:43 INFO - starting webserver on 'localhost:49279' 10:53:43 INFO - SUITE-START | Running 2 tests 10:53:43 INFO - TEST-START | dromaeo_css 10:53:43 INFO - operating with platform_type : mac_ 10:53:43 INFO - Initialising browser for dromaeo_css test... 10:53:43 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/tmpwU2xjd/profile http://localhost:49279/getInfo.html) 10:53:45 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 10:53:45 INFO - PROCESS | 1673 | colorDepth:24 10:53:45 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 10:53:45 INFO - PROCESS | 1673 | __metrics 10:53:46 INFO - PROCESS | 1673 | 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:53:46 INFO - PROCESS | 1673 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:53:46 INFO - TEST-INFO | 1673: exit 0 10:53:46 INFO - Browser initialized. 10:53:46 INFO - Running cycle 1/1 for dromaeo_css test... 10:53:46 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/tmpwU2xjd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:53:58 INFO - PROCESS | 1676 | RSS: Main: 243625984 10:53:58 INFO - PROCESS | 1676 | 10:54:49 INFO - PROCESS | 1676 | 1468605289961 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] 10:57:01 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 10:57:02 INFO - PROCESS | 1676 | RSS: Main: 357785600 10:57:02 INFO - PROCESS | 1676 | 11:00:06 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 11:00:06 INFO - PROCESS | 1676 | RSS: Main: 374960128 11:00:06 INFO - PROCESS | 1676 | 11:03:08 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 11:03:09 INFO - PROCESS | 1676 | RSS: Main: 373268480 11:03:09 INFO - PROCESS | 1676 | 11:06:11 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 11:06:12 INFO - PROCESS | 1676 | RSS: Main: 369664000 11:06:12 INFO - PROCESS | 1676 | 11:09:15 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 11:09:16 INFO - PROCESS | 1676 | RSS: Main: 335855616 11:09:16 INFO - PROCESS | 1676 | 11:12:19 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 11:12:20 INFO - PROCESS | 1676 | RSS: Main: 383778816 11:12:20 INFO - PROCESS | 1676 | 11:12:20 INFO - PROCESS | 1676 | __start_tp_report 11:12:20 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 11:12:20 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 11:12:20 INFO - PROCESS | 1676 | |i|pagename|runs| 11:12:20 INFO - PROCESS | 1676 | |0;dojo.html;3121.110483512199;3314.13832403575;3352.6479719629438;3355.530225768391;3371.730261579074;5625.718714064291;5667.829965101048;5710.885782284352;5750.108733146359;5769.16347129666;5725.742341594628;5760.337561180466;5763.250777398943;5786.537077033838;5804.970975145118;5176.430592634807;5229.398619158788;5252.5010124038345;5264.473552644724;5323.121684921984;4655.4878963314;5074.340335756347;5293.179557168645;5307.097793375136;5310.495502927216;5220.8694782630355;5221.190715439113;5240.44975277596;5251.5798736101215;5302.098643230641;5302.761375738081;5351;5354.437784032699;5378.220158077057;5378.704171270579;5120.539151476385;5131.846044618667;5175.508326708957;5261.105612045962;5272.604554658416;5660.235868157828;5720.3135623725;5723.484886360247;5737.311522617271;5747.310322761254;6256.092866534373;6284.465820405271;6286.339934306923;6297.779577714758;6313.116163737081;6113.174721412597;6254.155688981975;6332.430081292659;6348.777792777277;6370.044493325991;5844.415558444207;5846.298444186682;5860.472557469848;5864.824055278264;5868.295804503487;6288.654124023131;6296.33888441707;6311.33730958252;6321.747130114744;6356.2054743157105;6010.9699451502465;6068.557003289713;6136;6145.446909778052;6213.689315534205;6294.181756371642;6295.905561416583;6297.118403423525;6308.3060863305;6324.272708638566;6222.937770622327;6273.905891411632;6274.811755647338;6279;6281.685915704196;6102.4507794298725;6137.438273608381;6138.112525404833;6203.348648391854;6279.529035322278;4953.0589188054155;4973.980334031515;4974.228994505857;4995.625328100407;5012.348394708737;4481.7983190756495;4490.708103973297;4528.886777830515;4531.116432295671;4557.316402539645;5782.074868021181;5788.3343415506915;5791.565632577489;5807.88384232313;5875.177475153482;5138.486151384906;6191.473724733448;6244.906326405107;6307.716152773044;6317.715702793385;4846.733429661332;5755.8561035974435;5763.4812866842185;5808.709564521757;5847.976604094267;5454.481824226692;5481.287432633732;5499;5500.58745594082;5576.996140694633;6239.594426362272;6255.311915689362;6293.496520278458;6293.685315734287;6325.399087086719;6180.887440260796;6200.7519699211525;6260.843478912881;6261.092141639528;6266.467350275096;6320.841978950379;6348.143000695032;6355;6355.078513615592;6359.236891573041;6191.288001879841;6192.442680158807;6211.440970312694;6257.749690012531;6259.96870015647;6141.393954534099;6170.167027451416;6210.906836397548;6218.5647004711045;6331.620102793847;5504.972475137599;5760.222369979999;5998.0403135499855;6103.05402662588;6110.358412366638;6046.7581296749395;6082.878342433215;6141.109539116893;6144.754209831557;6150.10823430609;5845.766169353178;5980.252468441445;6012.789552365646;6035.668038257938;6058.697065146637;6140.478059364826;6167.167432396569;6177.258728952374;6179.134921110958;6180.47465965398;5781.277340332458;5844.298684157929;5848.736806843787;6009.549283803644;6104.26748790148 11:12:20 INFO - PROCESS | 1676 | |1;ext.html;473.6968340262231;505.58036829431575;520.5445235419008;527.7414067107118;553.7867920850472;31912.840435797818;33812.32375352494;42991.355129673044;43960.780196098975;44074.33888491675;45440.091198176015;45576.08847823041;45605;45762.542374576245;45770.54229457705;9651.131398174146;9839.852402213937;9842.704718858446;9854.310198286123;9859.21126309897;8849.33629977751;9064.954675226614;11901;12078.818817717742;12086.637400877988;12166.695832604168;12207.511699532053;12234.021278297714;12245.93877030618;12262.264264144136;7573.697052117964;9080.046595107484;10237.539310731036;12075.033997280196;12223.327716975607;8923.866142007875;8979.640814367418;11597.304161750237;11713.121515886258;11852.585159519376;42789;43150.56849431497;43213.56786432127;43214;43269.35095973563;3531.29374125176;3610.115521697201;3741.5136032315895;4087.2234275487563;4089;32182;33426;33838.661613383796;33857.66142338594;34049;3367.579052618423;3370.679785420381;3382.5940887093707;3440.4839274108836;3480.008197663679;503276;505148;506422;506478;507755;63266.683666581375;63562;64036.67981660062;64124.358756412774;64163.679181603795;67138;67377.32622673809;67429.66285168641;67435.66282168656;67491.31271718148;16472.670546589;16967.236474358684;16983.83016169847;17007;17024.404145854834;11873.940630296793;12196.451159697657;12814.67963300925;13039.022073344537;13135.080544361803;17557.473275801745;17721.468355949342;17788;17811.46565603034;17826.10869456548;6066.939330606726;7288.635568221568;7295.416366690758;7305.159906610702;7340.889886651705;937.5921474158763;990.7027891632481;1037.7457522906936;1160.1762748448527;1162.1342100135284;31187.376252474824;31280.843595781877;31373.8431307842;32187.51718724221;32250;916.9266458683289;1018.5620183321095;1027.7563799915542;1131.321207275628;1182.1606659272013;16226.026438413734;16236.75644865328;16335.346586136659;16341.754873676904;16775.916120419563;666174.6691266512;679225;680852;686734;687291;62355.06467402993;62533;62617.37382626115;62646;62700;1312.5734136405706;1350.9527166549124;1454.6363409147714;1454.941802327895;1488.0996996816987;30766.154030758225;32178.678213218504;32849.34301314008;32932.1766955828;33175.36962013901;53701.925961481335;55255.72372138114;55285;55349.893002138924;55522.444775551725;503832;628108;629220;629336;629411;38008.61991380051;38784.2243155141;46050.30924536067;46072.46601436819;46583;50893.49106508888;51417.22874156816;52389.952200956526;53855.461445385044;54133.72933135467;42012.57987420209;42791.14417711566;42961;43265.567344326155;43444.782776085915;42321.94195145146;42375;42390;43474.34788478112;43597.56402435935;40797;41396.1720765577;41583.7920810394;41658.58341416547;41665;35164.64835351716;35919.820400898876;36242.27515449624;36832.81583592065;36938.63061369459;26705.19884403471;26732.866335668197;26769.33076673099;26831;27163.456730865666 11:12:20 INFO - PROCESS | 1676 | |2;jquery.html;364.0806962419888;385.65098585779873;390.122224993764;391.17852509729573;411.0422715073878;15157.317920693567;15395.923020384897;20934.89532552335;21166;21193;20571.897140514313;21038.474038149056;21432.57134857302;21928.232511862087;21955.890220548914;5302.867428314285;5789.594728369016;5997.37027612101;6023.969880150593;6056.545759068087;5917.5561832862495;5963.045912653976;6011.759529618832;6019.337872834007;6027.246594175728;5655.293088363955;5879.323877754049;5889.322727886283;5922.378150294222;5956.374580669032;5633.15502674598;5853.239078919756;5887.35239123696;5915.704214789243;5945.108233764949;5122.129238029506;5134.666246693953;5136.845894623168;5154.046501397216;5156.097682905514;25889.611655825178;26177.73822261791;26227.868860655766;26401.73598264012;26700.33249168767;2265.7054582904284;2283.703118594589;2309.122533437283;2372.991478621579;2501.8999240030557;15421.614459638487;16144.75782863258;16281.348746050144;16480.752788708178;16771.83228167727;1862.9254829806791;1929.8456123510086;1943.5918457124021;1990.731251281073;2066.8553201275918;70701.99999999949;112042.3193652096;113978;120976;133816.99999999904;8961.551922403854;10780.197148741383;10935.125189984783;11110.111191104692;11238.55045798159;13666.726665466635;13744.03791734569;13769.655758606115;13788;13802.102863313852;8512.06367299609;8734.65061397537;8789.516576588348;8809.603567839462;8831.381803273838;8851.070637582961;8864;8876.911230887608;8932;9021.32340074497;6618.735250590019;6727.966360168168;6727.966360168168;6746.460283177334;6754.527183097136;1732.7227643576966;1802.8737988340952;1831.5238038110188;1839.2826797548967;1840.7883093444157;770.5492287012161;933.0343094896749;1003.8945910679273;1030.8402197659375;1054.1935419404163;20553.69169462459;21734.45663858416;22402;22712.545749084926;22740.317790466313;967.8644989701447;971.961121555144;993.7913038261902;1036.409246729357;1109.7447587055024;10084.49577521121;11743.941280293544;11884.702882428008;11919.880801191877;11923.105767067505;540539;548425;594358;597023;616086;102236;121581.39209303897;123997;125139;127166.72833271734;1010.8281592129356;1031.9432431216205;1043.0664555223034;1064.1274155192668;1069.187417562658;16849.494515164566;19587.902060490178;19708.40874773759;19720.80279197247;19795.604087917873;52823.60469534345;53043.734781325846;53151.93696125979;54888.45111548834;55023.72488137534;50246.995060099325;71327.64336178286;71416.57166856738;73948;74276;7979;8019.6391162398995;8092.433529652868;8163.83672326562;8379.45533540318;13329;13369.933150334185;13385.799213011618;13593.660158496117;13665.658358540719;6617.7683781067435;11463.426828658701;11472.31166130035;11578.594749183738;11795.41022948832;11478.713032174262;11514.136439766884;11518.712032199262;11563.132765042823;11747.765044698886;11655.475503602189;11842.526298947945;11873.762524749629;12038;12045.457954392248;7539.208383119693;7857.253560911704;7957.204279572228;7987.520748755093;8125.553094579855;10555.68332949982;10578.100861426865;10688.732781680521;10704.518296676513;10949.616763413242 11:12:20 INFO - PROCESS | 1676 | |3;mootools.html;1525.496586126578;1566.8433156684332;1754.7367894815777;1925.451246394777;1933.352326970465;8531.616077276522;8793.824123517526;8973.955130224356;8986.460812351264;8994;7875.4487185897015;8087.433879628428;8590.742277731662;8824.249938755214;8824.823503529926;2365.6096744037254;2397.328747950568;2398.5083057973075;2399.9880000599974;2408.169181632334;2354.1878052072007;2361.0437772702085;2401.7478164792706;2404.555157295897;2409.1567951217107;2090.651529763302;2183.759786423492;2308.6537019447132;2365.503244318695;2370.3600027992434;2301.8618882866913;2307.042577330406;2317.1310758465575;2324.6861673674007;2330.5338932213454;2359.28041947206;2369.9170529031576;2372.7864492195613;2375.7505461926594;2376.964345534818;8801.823963520694;8862.734117976472;8876.8668469973;8880.866786998136;8899.15458031486;5221.451747566527;5329.786808527693;5376.301080859464;5384.9461505385225;5395.082835917903;3634.6183650716557;3636.272745450894;3702.8148592570533;3708.684761795251;3721.665050145473;1094.9616763413242;1100.1418893263267;1101.9228653994223;1122.421952694363;1123.6741345009937;9935.956724543963;11807;11810;11814.822777658343;11816;4546.113507866001;4584.541545845389;4770.403699537558;4773.880652983634;4775.474697783241;10093.69718908434;10121.342112762646;10123.240756943258;10128.291019628703;10154.289199756095;5367.5169234769055;5376.462353764592;5377.0321342158;5380.704061276589;5390;4852.587530059914;4876.756162191876;4909.7790599423115;4909.852704418873;4943.826966056171;3990.2817492850986;4008.7594744315434;4016.2369149861433;4119.629233369011;4189.937150942738;1186.9287842729464;1231.2920070959235;1283.7881749511225;1321.6563693439582;1338.3174580963778;671.3655595462338;758.4501236603529;824.5382585751998;937.2736129499692;978.5498670611456;4995.650304478651;5876.941230587725;5932.377100404396;6227.906581401283;6332;1019.3017782818793;1029.3000759483473;1056.2131212246773;1077.8437126616755;1089.4661615808354;3970.8014599269923;4022.2960981828064;4117.21772863155;4136.358864376027;4180.581941805856;8955.731328060168;10445.321054131457;10603.204759642904;10716.517756700967;10751.677449676523;9751.561179746928;10423.843642345371;10578.682639520826;10664.626738064131;10691.144708423308;8116.472429292077;8424.873626895602;8442.62008209632;8451.915480845235;8461;6099.573029887865;6225.688715564294;6237.28271248794;6323.08315294289;6347.333529979285;10250.077493025663;10370.688879333633;10372.74068148272;10386.740331491774;10396.376217426978;10159.949200253952;10186.643467478603;10277.53751081218;10373.688789336333;10475.109615682752;7045.682944267623;7079.787606371817;7173.928260717326;7177.246389129066;7184.030155928883;8138.796530086795;8319;8386.52597483756;8388.328933685412;8390.790230244049;5496.80761173357;6772.492063095189;7509.098908131059;7521.5111017783665;7584.061325568039;7541.095068591804;7545.170031296663;7562.621868906643;7698.191689872483;7701.6534255957495;7143.85712285741;7149.213586505376;7205.315495027964;7216.5670059796585;7226.277372262732;5089.109405854035;5108.540231379194;5148.536631703165;5175.14610089331;5267.71027593486;5058.772355243949;5078.984203159457;5079.161938280143;5298.072837253542;5299.602529810357 11:12:20 INFO - PROCESS | 1676 | |4;prototype.html;304.8049248480972;306.77452072726555;312.8983080498837;320.94062598419293;323.6666233779208;5225.282852916491;5349.598780091489;5394.271773310602;5433.755481003354;5451.536619387357;3905.17991221843;4891.266310053494;5174.249733788598;5319.6808191508535;5400.378956420013;3251.8048917064934;3733.8693145739903;4079.653229475498;4103.302438585448;4120.979395103021;3816.179521402898;3927.8428862845594;3974.503187101612;4107.774072426016;4187.60217779312;3418.965810341902;4057.8173982170874;4067;4083;4113.177364527091;3724.944125838115;3839.2705385976574;4087.632113109805;4098.180363927211;4143.399207114951;2969.6436427628605;3137.4823154179535;3477.7043951264172;3583.835253542583;3596.4355505355256;4016.196760647882;4687.88280292992;4941.776200709659;4971.8259860905055;5029.7736601853;4315.784210789479;4406.052698669801;4606.884827879297;4660.766961651938;4661.207594708908;5654.264945557093;5675.829725108253;5681.403452637455;5746.626469279484;5767.36558978512;3601.94597081044;3843.7117216208894;3866;3889.3193691104225;3895.2599006188734;4924.876878078041;5541.251930989305;5861.501772349313;5903.144044113623;5984.401559844068;3944.802759862053;3957.8021098944937;3960.6039396060164;3979.880603581897;3986.3223252047223;3832.425136229532;3968.8015599220503;3997.340438827619;4009.91980160401;4027.5771044039952;4626.745528995937;4630;4657;4673.158831410379;4694.6009589185305;5276.551493123051;5752.9712351437975;5777.844431113794;5789.884202316014;5841.88316233673;1204.8132539456399;1265;1316.453671726242;1373.7870630052357;1405.2622373254123;849.3332733804058;938.7183844846519;949.4018768176143;961.053362437998;966.6375109334;4097.631213190767;4240.681948853849;4341.60925516705;4371;4383.035732138925;3952.6442620164325;4136.98643832263;4325.307950727868;4471.3679506979715;4925.605951523869;4278;4347.326164444453;4368.36658684489;4384.364267181241;4393.75834329115;2286.8742219178107;2448.571499987531;2478.3060742991993;2479.367761220877;2504.5742223821994;4504.211762941472;4794.506856434635;4982.701037937735;5055.848324550269;5081.390233171969;4131.731437456556;4447.465624359591;4605.286180642006;4880.219164933665;5036.64743467961;4331.5884990925815;4672.159011377985;4788.449328327218;6088.299845517822;6134.785282515001;5851.97590421666;5895.882082358243;5898.587098903207;5903.085021821625;5914.58597898143;4695.413073365829;5019.548240658212;5360.490753378423;5388.191771234189;5841.824745257649;6003.279606447254;6006.429389208019;6029.216201893726;6045.788397406246;6075.210222671025;3313.2334500691227;3476.061463404917;3854.5160113356;3911.7677931451503;3942.9408558872215;5711.8857622848145;5923.733431995484;5935.643861368331;5939.91090133657;5979.641221526722;4742.857714268577;5698.660814708623;5739.598228123991;5761.39505351932;5808.82573522795;5044.646874718881;5654.434556544313;5726.2269593606;5749.7125143741805;5771.624844385102;4929.028388644428;4932.457429682804;5590.385057643737;5610.607257491936;5671.291088613923;5219.556337711337;5632.662849605863;5662.886742265049;5763.6541807491685;5767.971160144172;4369.388285640077;4915.5330243626795;4970.304157417892;5642.771105413257;5651.7784862627905 11:12:20 INFO - PROCESS | 1676 | |5;yui.html;658.9176352955881;802.2178376083319;837.1670188162778;843.9156084391561;869.7216890595008;15151.545453636394;16479.258433370498;16567.42014029508;17488.3004679813;18256;17110.230039648246;17266.654666906656;17283.222254998524;17288.22203000865;17440.127993600334;707.2750430808437;726.6766289001397;877.9209871111599;895.3150839607705;898.1333013641835;866.1511718515858;896.5830888636783;899;900.2392977933636;900.275278400887;927.3184209605935;931.9347645664806;934.1965909317981;937.8499440089587;937.8921424036221;733.0580204437326;898.3172788680649;923.2337160157053;927.1192367251105;932.1424289653535;690.1407747354525;690.8065741592362;692.594832023267;692.62944324786;693.1647364925253;15663;15717.449889253821;15736;15736.527904162893;15760.605984850356;13793.379297931517;13918.58244252674;13971.161730296113;14061.5078472254;14138.646533836634;12396.75206495874;12917.031222658245;13305.00212484058;13509.189448633015;13583.320833958263;4560.908781824345;4608.539146085398;4621.584057434847;4791.161546729343;4807.18277892759;102778.48610756898;103308.96691033068;103344;104131;106451.46774266157;11053.336799792038;11161.218714690056;11480.712982175346;11683.649490515298;11798.233114847508;22836.4290892729;22964.770352296597;23109;23231.767682323298;23256.76743232546;8412.94838145232;8427.915720842835;8482.236598706146;8527.786805329917;8647.3946823723;4579.702319349231;4706.1528924793865;4709;4720.315554244616;4881.974785295057;3460.463628137643;3594.712423006153;3594.9101272468397;3610.891673249807;3818.5417749870194;685.5509641185032;689.334791925794;700.5061431690646;702.2766550453042;710.0414440505276;556.3824155187739;637.9904301435482;663.3466035954581;678.3250665587772;707.7239181899033;9366.637374203841;9590.760230994281;9615;9788.9021109788;9829.950850245847;607.4077774170237;635.1358762033354;642.601587016053;670.7954074007474;674.6120980436269;3607.4408466687705;4717;4718.339432479455;4724.622030237572;4745.667803253808;107000.71997120028;123936;127777.22227777148;128694;172506;116994.41502792432;118925;119795;122600.38699806444;124768.75231247753;75427;77692.83460748206;109639;110291;114700;9974.351667141613;10169.542370593193;10241.027102425258;10272.948635256776;10296.330738501973;57311.85376292534;61563.38436615756;62057.379426206964;63551;66589;64845.67577162084;74846;82884.58557707173;89655.65516517388;95030.52484737532;14537.200453975132;14615.707685846437;14866.702665946837;14977;15313.387464501295;15517.095737121746;15707.921460392625;15930.761038584198;16145.515634530982;16215.945963512333;14436.422543098159;14675.19286439256;14751.852481475476;14797.556073317817;14922.552323430315;14451.27743612794;14679.41282348694;14683.41266349334;15151.393944242547;15169.696606068037;13788;14001.159930404208;14200.78698819498;14362.640933976734;14389.42442302338;11312.15158863072;11567.595134170264;11716.94141529321;11784.4696988634;11820.881791181979;11031.282966607145;11159.776804464027;11484.655460336204;11758.529658813894;11782.293062416282 11:12:20 INFO - PROCESS | 1676 | __end_tp_report 11:12:20 INFO - PROCESS | 1676 | __start_cc_report 11:12:20 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,1170 11:12:20 INFO - PROCESS | 1676 | __end_cc_report 11:12:20 INFO - PROCESS | 1676 | __startTimestamp1468606340020__endTimestamp 11:12:20 INFO - PROCESS | 1676 | 11:12:20 INFO - PROCESS | 1676 | ------- Summary: start ------- 11:12:20 INFO - PROCESS | 1676 | Number of tests: 6 11:12:20 INFO - PROCESS | 1676 | 11:12:20 INFO - PROCESS | 1676 | [#0] dojo.html Cycles:165 Average:5760.66 Median:5980.25 stddev:645.83 (10.8%) stddev-sans-first:613.72 11:12:20 INFO - PROCESS | 1676 | Values: 3121.1 3314.1 3352.6 3355.5 3371.7 5625.7 5667.8 5710.9 5750.1 5769.2 5725.7 5760.3 5763.3 5786.5 5805.0 5176.4 5229.4 5252.5 5264.5 5323.1 4655.5 5074.3 5293.2 5307.1 5310.5 5220.9 5221.2 5240.4 5251.6 5302.1 5302.8 5351.0 5354.4 5378.2 5378.7 5120.5 5131.8 5175.5 5261.1 5272.6 5660.2 5720.3 5723.5 5737.3 5747.3 6256.1 6284.5 6286.3 6297.8 6313.1 6113.2 6254.2 6332.4 6348.8 6370.0 5844.4 5846.3 5860.5 5864.8 5868.3 6288.7 6296.3 6311.3 6321.7 6356.2 6011.0 6068.6 6136.0 6145.4 6213.7 6294.2 6295.9 6297.1 6308.3 6324.3 6222.9 6273.9 6274.8 6279.0 6281.7 6102.5 6137.4 6138.1 6203.3 6279.5 4953.1 4974.0 4974.2 4995.6 5012.3 4481.8 4490.7 4528.9 4531.1 4557.3 5782.1 5788.3 5791.6 5807.9 5875.2 5138.5 6191.5 6244.9 6307.7 6317.7 4846.7 5755.9 5763.5 5808.7 5848.0 5454.5 5481.3 5499.0 5500.6 5577.0 6239.6 6255.3 6293.5 6293.7 6325.4 6180.9 6200.8 6260.8 6261.1 6266.5 6320.8 6348.1 6355.0 6355.1 6359.2 6191.3 6192.4 6211.4 6257.7 6260.0 6141.4 6170.2 6210.9 6218.6 6331.6 5505.0 5760.2 5998.0 6103.1 6110.4 6046.8 6082.9 6141.1 6144.8 6150.1 5845.8 5980.3 6012.8 6035.7 6058.7 6140.5 6167.2 6177.3 6179.1 6180.5 5781.3 5844.3 5848.7 6009.5 6104.3 11:12:20 INFO - PROCESS | 1676 | 11:12:20 INFO - PROCESS | 1676 | [#1] ext.html Cycles:180 Average:74603.86 Median:42167.26 stddev:160596.12 (380.9%) stddev-sans-first:160950.19 11:12:20 INFO - PROCESS | 1676 | Values: 473.7 505.6 520.5 527.7 553.8 31912.8 33812.3 42991.4 43960.8 44074.3 45440.1 45576.1 45605.0 45762.5 45770.5 9651.1 9839.9 9842.7 9854.3 9859.2 8849.3 9065.0 11901.0 12078.8 12086.6 12166.7 12207.5 12234.0 12245.9 12262.3 7573.7 9080.0 10237.5 12075.0 12223.3 8923.9 8979.6 11597.3 11713.1 11852.6 42789.0 43150.6 43213.6 43214.0 43269.4 3531.3 3610.1 3741.5 4087.2 4089.0 32182.0 33426.0 33838.7 33857.7 34049.0 3367.6 3370.7 3382.6 3440.5 3480.0 503276.0 505148.0 506422.0 506478.0 507755.0 63266.7 63562.0 64036.7 64124.4 64163.7 67138.0 67377.3 67429.7 67435.7 67491.3 16472.7 16967.2 16983.8 17007.0 17024.4 11873.9 12196.5 12814.7 13039.0 13135.1 17557.5 17721.5 17788.0 17811.5 17826.1 6066.9 7288.6 7295.4 7305.2 7340.9 937.6 990.7 1037.7 1160.2 1162.1 31187.4 31280.8 31373.8 32187.5 32250.0 916.9 1018.6 1027.8 1131.3 1182.2 16226.0 16236.8 16335.3 16341.8 16775.9 666174.7 679225.0 680852.0 686734.0 687291.0 62355.1 62533.0 62617.4 62646.0 62700.0 1312.6 1351.0 1454.6 1454.9 1488.1 30766.2 32178.7 32849.3 32932.2 33175.4 53701.9 55255.7 55285.0 55349.9 55522.4 503832.0 628108.0 629220.0 629336.0 629411.0 38008.6 38784.2 46050.3 46072.5 46583.0 50893.5 51417.2 52390.0 53855.5 54133.7 42012.6 42791.1 42961.0 43265.6 43444.8 42321.9 42375.0 42390.0 43474.3 43597.6 40797.0 41396.2 41583.8 41658.6 41665.0 35164.6 35919.8 36242.3 36832.8 36938.6 26705.2 26732.9 26769.3 26831.0 27163.5 11:12:20 INFO - PROCESS | 1676 | 11:12:20 INFO - PROCESS | 1676 | [#2] jquery.html Cycles:180 Average:34114.03 Median:12489.13 stddev:96523.83 (772.9%) stddev-sans-first:96761.34 11:12:20 INFO - PROCESS | 1676 | Values: 364.1 385.7 390.1 391.2 411.0 15157.3 15395.9 20934.9 21166.0 21193.0 20571.9 21038.5 21432.6 21928.2 21955.9 5302.9 5789.6 5997.4 6024.0 6056.5 5917.6 5963.0 6011.8 6019.3 6027.2 5655.3 5879.3 5889.3 5922.4 5956.4 5633.2 5853.2 5887.4 5915.7 5945.1 5122.1 5134.7 5136.8 5154.0 5156.1 25889.6 26177.7 26227.9 26401.7 26700.3 2265.7 2283.7 2309.1 2373.0 2501.9 15421.6 16144.8 16281.3 16480.8 16771.8 1862.9 1929.8 1943.6 1990.7 2066.9 70702.0 112042.3 113978.0 120976.0 133817.0 8961.6 10780.2 10935.1 11110.1 11238.6 13666.7 13744.0 13769.7 13788.0 13802.1 8512.1 8734.7 8789.5 8809.6 8831.4 8851.1 8864.0 8876.9 8932.0 9021.3 6618.7 6728.0 6728.0 6746.5 6754.5 1732.7 1802.9 1831.5 1839.3 1840.8 770.5 933.0 1003.9 1030.8 1054.2 20553.7 21734.5 22402.0 22712.5 22740.3 967.9 972.0 993.8 1036.4 1109.7 10084.5 11743.9 11884.7 11919.9 11923.1 540539.0 548425.0 594358.0 597023.0 616086.0 102236.0 121581.4 123997.0 125139.0 127166.7 1010.8 1031.9 1043.1 1064.1 1069.2 16849.5 19587.9 19708.4 19720.8 19795.6 52823.6 53043.7 53151.9 54888.5 55023.7 50247.0 71327.6 71416.6 73948.0 74276.0 7979.0 8019.6 8092.4 8163.8 8379.5 13329.0 13369.9 13385.8 13593.7 13665.7 6617.8 11463.4 11472.3 11578.6 11795.4 11478.7 11514.1 11518.7 11563.1 11747.8 11655.5 11842.5 11873.8 12038.0 12045.5 7539.2 7857.3 7957.2 7987.5 8125.6 10555.7 10578.1 10688.7 10704.5 10949.6 11:12:20 INFO - PROCESS | 1676 | 11:12:20 INFO - PROCESS | 1676 | [#3] mootools.html Cycles:180 Average:5750.58 Median:4774.68 stddev:3180.15 (66.6%) stddev-sans-first:3173.22 11:12:20 INFO - PROCESS | 1676 | Values: 1525.5 1566.8 1754.7 1925.5 1933.4 8531.6 8793.8 8974.0 8986.5 8994.0 7875.4 8087.4 8590.7 8824.2 8824.8 2365.6 2397.3 2398.5 2400.0 2408.2 2354.2 2361.0 2401.7 2404.6 2409.2 2090.7 2183.8 2308.7 2365.5 2370.4 2301.9 2307.0 2317.1 2324.7 2330.5 2359.3 2369.9 2372.8 2375.8 2377.0 8801.8 8862.7 8876.9 8880.9 8899.2 5221.5 5329.8 5376.3 5384.9 5395.1 3634.6 3636.3 3702.8 3708.7 3721.7 1095.0 1100.1 1101.9 1122.4 1123.7 9936.0 11807.0 11810.0 11814.8 11816.0 4546.1 4584.5 4770.4 4773.9 4775.5 10093.7 10121.3 10123.2 10128.3 10154.3 5367.5 5376.5 5377.0 5380.7 5390.0 4852.6 4876.8 4909.8 4909.9 4943.8 3990.3 4008.8 4016.2 4119.6 4189.9 1186.9 1231.3 1283.8 1321.7 1338.3 671.4 758.5 824.5 937.3 978.5 4995.7 5876.9 5932.4 6227.9 6332.0 1019.3 1029.3 1056.2 1077.8 1089.5 3970.8 4022.3 4117.2 4136.4 4180.6 8955.7 10445.3 10603.2 10716.5 10751.7 9751.6 10423.8 10578.7 10664.6 10691.1 8116.5 8424.9 8442.6 8451.9 8461.0 6099.6 6225.7 6237.3 6323.1 6347.3 10250.1 10370.7 10372.7 10386.7 10396.4 10159.9 10186.6 10277.5 10373.7 10475.1 7045.7 7079.8 7173.9 7177.2 7184.0 8138.8 8319.0 8386.5 8388.3 8390.8 5496.8 6772.5 7509.1 7521.5 7584.1 7541.1 7545.2 7562.6 7698.2 7701.7 7143.9 7149.2 7205.3 7216.6 7226.3 5089.1 5108.5 5148.5 5175.1 5267.7 5058.8 5079.0 5079.2 5298.1 5299.6 11:12:20 INFO - PROCESS | 1676 | 11:12:20 INFO - PROCESS | 1676 | [#4] prototype.html Cycles:180 Average:4412.59 Median:4672.66 stddev:1384.82 (29.6%) stddev-sans-first:1353.95 11:12:20 INFO - PROCESS | 1676 | Values: 304.8 306.8 312.9 320.9 323.7 5225.3 5349.6 5394.3 5433.8 5451.5 3905.2 4891.3 5174.2 5319.7 5400.4 3251.8 3733.9 4079.7 4103.3 4121.0 3816.2 3927.8 3974.5 4107.8 4187.6 3419.0 4057.8 4067.0 4083.0 4113.2 3724.9 3839.3 4087.6 4098.2 4143.4 2969.6 3137.5 3477.7 3583.8 3596.4 4016.2 4687.9 4941.8 4971.8 5029.8 4315.8 4406.1 4606.9 4660.8 4661.2 5654.3 5675.8 5681.4 5746.6 5767.4 3601.9 3843.7 3866.0 3889.3 3895.3 4924.9 5541.3 5861.5 5903.1 5984.4 3944.8 3957.8 3960.6 3979.9 3986.3 3832.4 3968.8 3997.3 4009.9 4027.6 4626.7 4630.0 4657.0 4673.2 4694.6 5276.6 5753.0 5777.8 5789.9 5841.9 1204.8 1265.0 1316.5 1373.8 1405.3 849.3 938.7 949.4 961.1 966.6 4097.6 4240.7 4341.6 4371.0 4383.0 3952.6 4137.0 4325.3 4471.4 4925.6 4278.0 4347.3 4368.4 4384.4 4393.8 2286.9 2448.6 2478.3 2479.4 2504.6 4504.2 4794.5 4982.7 5055.8 5081.4 4131.7 4447.5 4605.3 4880.2 5036.6 4331.6 4672.2 4788.4 6088.3 6134.8 5852.0 5895.9 5898.6 5903.1 5914.6 4695.4 5019.5 5360.5 5388.2 5841.8 6003.3 6006.4 6029.2 6045.8 6075.2 3313.2 3476.1 3854.5 3911.8 3942.9 5711.9 5923.7 5935.6 5939.9 5979.6 4742.9 5698.7 5739.6 5761.4 5808.8 5044.6 5654.4 5726.2 5749.7 5771.6 4929.0 4932.5 5590.4 5610.6 5671.3 5219.6 5632.7 5662.9 5763.7 5768.0 4369.4 4915.5 4970.3 5642.8 5651.8 11:12:20 INFO - PROCESS | 1676 | 11:12:20 INFO - PROCESS | 1676 | [#5] yui.html Cycles:180 Average:23879.03 Median:23244.27 stddev:36144.64 (155.5%) stddev-sans-first:36203.99 11:12:20 INFO - PROCESS | 1676 | Values: 658.9 802.2 837.2 843.9 869.7 15151.5 16479.3 16567.4 17488.3 18256.0 17110.2 17266.7 17283.2 17288.2 17440.1 707.3 726.7 877.9 895.3 898.1 866.2 896.6 899.0 900.2 900.3 927.3 931.9 934.2 937.8 937.9 733.1 898.3 923.2 927.1 932.1 690.1 690.8 692.6 692.6 693.2 15663.0 15717.4 15736.0 15736.5 15760.6 13793.4 13918.6 13971.2 14061.5 14138.6 12396.8 12917.0 13305.0 13509.2 13583.3 4560.9 4608.5 4621.6 4791.2 4807.2 102778.5 103309.0 103344.0 104131.0 106451.5 11053.3 11161.2 11480.7 11683.6 11798.2 22836.4 22964.8 23109.0 23231.8 23256.8 8412.9 8427.9 8482.2 8527.8 8647.4 4579.7 4706.2 4709.0 4720.3 4882.0 3460.5 3594.7 3594.9 3610.9 3818.5 685.6 689.3 700.5 702.3 710.0 556.4 638.0 663.3 678.3 707.7 9366.6 9590.8 9615.0 9788.9 9830.0 607.4 635.1 642.6 670.8 674.6 3607.4 4717.0 4718.3 4724.6 4745.7 107000.7 123936.0 127777.2 128694.0 172506.0 116994.4 118925.0 119795.0 122600.4 124768.8 75427.0 77692.8 109639.0 110291.0 114700.0 9974.4 10169.5 10241.0 10272.9 10296.3 57311.9 61563.4 62057.4 63551.0 66589.0 64845.7 74846.0 82884.6 89655.7 95030.5 14537.2 14615.7 14866.7 14977.0 15313.4 15517.1 15707.9 15930.8 16145.5 16215.9 14436.4 14675.2 14751.9 14797.6 14922.6 14451.3 14679.4 14683.4 15151.4 15169.7 13788.0 14001.2 14200.8 14362.6 14389.4 11312.2 11567.6 11716.9 11784.5 11820.9 11031.3 11159.8 11484.7 11758.5 11782.3 11:12:20 INFO - PROCESS | 1676 | -------- Summary: end -------- 11:12:20 INFO - PROCESS | 1676 | 11:12:20 INFO - TEST-INFO | 1676: exit 0 11:12:20 INFO - TEST-OK | dromaeo_css | took 1116934ms 11:12:20 INFO - TEST-START | kraken 11:12:20 INFO - operating with platform_type : mac_ 11:12:20 INFO - Initialising browser for kraken test... 11:12:20 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/tmpKJ_x06/profile http://localhost:49279/getInfo.html) 11:12:22 INFO - PROCESS | 1683 | __metrics Screen width/height:1600/1200 11:12:22 INFO - PROCESS | 1683 | colorDepth:24 11:12:22 INFO - PROCESS | 1683 | Browser inner width/height: 1024/689 11:12:22 INFO - PROCESS | 1683 | __metrics 11:12:22 INFO - PROCESS | 1683 | 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" 11:12:22 INFO - PROCESS | 1683 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:12:23 INFO - TEST-INFO | 1683: exit 0 11:12:23 INFO - Browser initialized. 11:12:23 INFO - Running cycle 1/1 for kraken test... 11:12:23 INFO - TEST-INFO | started process 1684 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/tmpKJ_x06/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:12:35 INFO - PROCESS | 1684 | RSS: Main: 243544064 11:12:35 INFO - PROCESS | 1684 | 11:12:38 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 11:12:38 INFO - PROCESS | 1684 | RSS: Main: 294043648 11:12:38 INFO - PROCESS | 1684 | 11:12:41 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 11:12:42 INFO - PROCESS | 1684 | RSS: Main: 304209920 11:12:42 INFO - PROCESS | 1684 | 11:12:45 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 11:12:46 INFO - PROCESS | 1684 | RSS: Main: 392740864 11:12:46 INFO - PROCESS | 1684 | 11:12:48 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 11:12:49 INFO - PROCESS | 1684 | RSS: Main: 292139008 11:12:49 INFO - PROCESS | 1684 | 11:12:51 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:12:51 INFO - PROCESS | 1684 | RSS: Main: 295882752 11:12:51 INFO - PROCESS | 1684 | 11:12:54 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 11:12:55 INFO - PROCESS | 1684 | RSS: Main: 334700544 11:12:55 INFO - PROCESS | 1684 | 11:12:58 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 11:12:58 INFO - PROCESS | 1684 | RSS: Main: 335671296 11:12:58 INFO - PROCESS | 1684 | 11:13:01 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 11:13:02 INFO - PROCESS | 1684 | RSS: Main: 335220736 11:13:02 INFO - PROCESS | 1684 | 11:13:03 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:13:04 INFO - PROCESS | 1684 | RSS: Main: 311427072 11:13:04 INFO - PROCESS | 1684 | 11:13:06 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:13:06 INFO - PROCESS | 1684 | RSS: Main: 318480384 11:13:06 INFO - PROCESS | 1684 | 11:13:08 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:13:09 INFO - PROCESS | 1684 | RSS: Main: 315756544 11:13:09 INFO - PROCESS | 1684 | 11:13:12 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:13:12 INFO - PROCESS | 1684 | RSS: Main: 324714496 11:13:12 INFO - PROCESS | 1684 | 11:13:15 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:13:16 INFO - PROCESS | 1684 | RSS: Main: 326193152 11:13:16 INFO - PROCESS | 1684 | 11:13:17 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 11:13:18 INFO - PROCESS | 1684 | RSS: Main: 369455104 11:13:18 INFO - PROCESS | 1684 | 11:13:18 INFO - PROCESS | 1684 | __start_tp_report 11:13:18 INFO - PROCESS | 1684 | _x_x_mozilla_page_load 11:13:18 INFO - PROCESS | 1684 | _x_x_mozilla_page_load_details 11:13:18 INFO - PROCESS | 1684 | |i|pagename|runs| 11:13:18 INFO - PROCESS | 1684 | |0;ai-astar;101;111;114;112;113;111;160;112;112;111 11:13:18 INFO - PROCESS | 1684 | |1;audio-beat-detection;137;108;104;183;139;138;108;127;145;104 11:13:18 INFO - PROCESS | 1684 | |2;audio-dft;183;185;185;186;182;181;197;264;183;184 11:13:18 INFO - PROCESS | 1684 | |3;audio-fft;79;74;58;106;59;72;84;85;89;49 11:13:18 INFO - PROCESS | 1684 | |4;audio-oscillator;89;87;92;71;69;68;97;70;68;100 11:13:18 INFO - PROCESS | 1684 | |5;imaging-gaussian-blur;86;84;88;84;87;84;88;84;87;84 11:13:18 INFO - PROCESS | 1684 | |6;imaging-darkroom;92;93;93;93;92;93;93;92;92;92 11:13:18 INFO - PROCESS | 1684 | |7;imaging-desaturate;85;85;87;83;84;82;85;85;84;86 11:13:18 INFO - PROCESS | 1684 | |8;json-parse-financial;56;54;54;53;54;54;56;54;53;55 11:13:18 INFO - PROCESS | 1684 | |9;json-stringify-tinderbox;62;69;70;77;72;71;70;70;69;71 11:13:18 INFO - PROCESS | 1684 | |10;stanford-crypto-aes;87;82;82;83;84;79;79;81;83;83 11:13:18 INFO - PROCESS | 1684 | |11;stanford-crypto-ccm;137;135;135;136;134;134;136;133;185;128 11:13:18 INFO - PROCESS | 1684 | |12;stanford-crypto-pbkdf2;160;165;162;161;163;161;160;163;162;164 11:13:18 INFO - PROCESS | 1684 | |13;stanford-crypto-sha256-iterative;66;66;66;66;67;66;65;66;64;65 11:13:18 INFO - PROCESS | 1684 | __end_tp_report 11:13:18 INFO - PROCESS | 1684 | __start_cc_report 11:13:18 INFO - PROCESS | 1684 | _x_x_mozilla_cycle_collect,2059 11:13:18 INFO - PROCESS | 1684 | __end_cc_report 11:13:18 INFO - PROCESS | 1684 | __startTimestamp1468606398246__endTimestamp 11:13:18 INFO - PROCESS | 1684 | 11:13:18 INFO - PROCESS | 1684 | ------- Summary: start ------- 11:13:18 INFO - PROCESS | 1684 | Number of tests: 14 11:13:18 INFO - PROCESS | 1684 | 11:13:18 INFO - PROCESS | 1684 | [#0] ai-astar Cycles:10 Average:115.70 Median:112.00 stddev:15.97 (14.3%) stddev-sans-first:16.03 11:13:18 INFO - PROCESS | 1684 | Values: 101.0 111.0 114.0 112.0 113.0 111.0 160.0 112.0 112.0 111.0 11:13:18 INFO - PROCESS | 1684 | 11:13:18 INFO - PROCESS | 1684 | [#1] audio-beat-detection Cycles:10 Average:129.30 Median:137.50 stddev:24.84 (18.1%) stddev-sans-first:26.19 11:13:18 INFO - PROCESS | 1684 | Values: 137.0 108.0 104.0 183.0 139.0 138.0 108.0 127.0 145.0 104.0 11:13:18 INFO - PROCESS | 1684 | 11:13:18 INFO - PROCESS | 1684 | [#2] audio-dft Cycles:10 Average:193.00 Median:185.00 stddev:25.34 (13.7%) stddev-sans-first:26.62 11:13:18 INFO - PROCESS | 1684 | Values: 183.0 185.0 185.0 186.0 182.0 181.0 197.0 264.0 183.0 184.0 11:13:18 INFO - PROCESS | 1684 | 11:13:18 INFO - PROCESS | 1684 | [#3] audio-fft Cycles:10 Average:75.50 Median:76.50 stddev:16.94 (22.1%) stddev-sans-first:17.92 11:13:18 INFO - PROCESS | 1684 | Values: 79.0 74.0 58.0 106.0 59.0 72.0 84.0 85.0 89.0 49.0 11:13:18 INFO - PROCESS | 1684 | 11:13:18 INFO - PROCESS | 1684 | [#4] audio-oscillator Cycles:10 Average:81.10 Median:79.00 stddev:13.08 (16.6%) stddev-sans-first:13.56 11:13:18 INFO - PROCESS | 1684 | Values: 89.0 87.0 92.0 71.0 69.0 68.0 97.0 70.0 68.0 100.0 11:13:18 INFO - PROCESS | 1684 | 11:13:18 INFO - PROCESS | 1684 | [#5] imaging-gaussian-blur Cycles:10 Average:85.60 Median:86.50 stddev:1.78 (2.1%) stddev-sans-first:1.88 11:13:18 INFO - PROCESS | 1684 | Values: 86.0 84.0 88.0 84.0 87.0 84.0 88.0 84.0 87.0 84.0 11:13:18 INFO - PROCESS | 1684 | 11:13:18 INFO - PROCESS | 1684 | [#6] imaging-darkroom Cycles:10 Average:92.50 Median:93.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 11:13:18 INFO - PROCESS | 1684 | Values: 92.0 93.0 93.0 93.0 92.0 93.0 93.0 92.0 92.0 92.0 11:13:18 INFO - PROCESS | 1684 | 11:13:18 INFO - PROCESS | 1684 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:85.00 stddev:1.43 (1.7%) stddev-sans-first:1.51 11:13:18 INFO - PROCESS | 1684 | Values: 85.0 85.0 87.0 83.0 84.0 82.0 85.0 85.0 84.0 86.0 11:13:18 INFO - PROCESS | 1684 | 11:13:18 INFO - PROCESS | 1684 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:54.00 stddev:1.06 (2.0%) stddev-sans-first:0.93 11:13:18 INFO - PROCESS | 1684 | Values: 56.0 54.0 54.0 53.0 54.0 54.0 56.0 54.0 53.0 55.0 11:13:18 INFO - PROCESS | 1684 | 11:13:18 INFO - PROCESS | 1684 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:70.50 stddev:3.67 (5.2%) stddev-sans-first:2.45 11:13:18 INFO - PROCESS | 1684 | Values: 62.0 69.0 70.0 77.0 72.0 71.0 70.0 70.0 69.0 71.0 11:13:18 INFO - PROCESS | 1684 | 11:13:18 INFO - PROCESS | 1684 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:83.00 stddev:2.36 (2.8%) stddev-sans-first:1.79 11:13:18 INFO - PROCESS | 1684 | Values: 87.0 82.0 82.0 83.0 84.0 79.0 79.0 81.0 83.0 83.0 11:13:18 INFO - PROCESS | 1684 | 11:13:18 INFO - PROCESS | 1684 | [#11] stanford-crypto-ccm Cycles:10 Average:139.30 Median:135.50 stddev:16.25 (12.0%) stddev-sans-first:17.21 11:13:18 INFO - PROCESS | 1684 | Values: 137.0 135.0 135.0 136.0 134.0 134.0 136.0 133.0 185.0 128.0 11:13:18 INFO - PROCESS | 1684 | 11:13:18 INFO - PROCESS | 1684 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.10 Median:162.50 stddev:1.66 (1.0%) stddev-sans-first:1.58 11:13:18 INFO - PROCESS | 1684 | Values: 160.0 165.0 162.0 161.0 163.0 161.0 160.0 163.0 162.0 164.0 11:13:18 INFO - PROCESS | 1684 | 11:13:18 INFO - PROCESS | 1684 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.00 stddev:0.82 (1.2%) stddev-sans-first:0.87 11:13:18 INFO - PROCESS | 1684 | Values: 66.0 66.0 66.0 66.0 67.0 66.0 65.0 66.0 64.0 65.0 11:13:18 INFO - PROCESS | 1684 | -------- Summary: end -------- 11:13:18 INFO - PROCESS | 1684 | 11:13:19 INFO - TEST-INFO | 1684: exit 0 11:13:19 INFO - TEST-OK | kraken | took 58654ms 11:13:19 INFO - SUITE-END | took 1175s 11:13:19 INFO - Completed test suite (00:19:35) 11:13:19 INFO - javascript benchmark 11:13:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3121.110483512199, 3314.13832403575, 3352.6479719629438, 3355.530225768391, 3371.730261579074, 5625.718714064291, 5667.829965101048, 5710.885782284352, 5750.108733146359, 5769.16347129666, 5725.742341594628, 5760.337561180466, 5763.250777398943, 5786.537077033838, 5804.970975145118, 5176.430592634807, 5229.398619158788, 5252.5010124038345, 5264.473552644724, 5323.121684921984, 4655.4878963314, 5074.340335756347, 5293.179557168645, 5307.097793375136, 5310.495502927216, 5220.8694782630355, 5221.190715439113, 5240.44975277596, 5251.5798736101215, 5302.098643230641, 5302.761375738081, 5351.0, 5354.437784032699, 5378.220158077057, 5378.704171270579, 5120.539151476385, 5131.846044618667, 5175.508326708957, 5261.105612045962, 5272.604554658416, 5660.235868157828, 5720.3135623725, 5723.484886360247, 5737.311522617271, 5747.310322761254, 6256.092866534373, 6284.465820405271, 6286.339934306923, 6297.779577714758, 6313.116163737081, 6113.174721412597, 6254.155688981975, 6332.430081292659, 6348.777792777277, 6370.044493325991, 5844.415558444207, 5846.298444186682, 5860.472557469848, 5864.824055278264, 5868.295804503487, 6288.654124023131, 6296.33888441707, 6311.33730958252, 6321.747130114744, 6356.2054743157105, 6010.9699451502465, 6068.557003289713, 6136.0, 6145.446909778052, 6213.689315534205, 6294.181756371642, 6295.905561416583, 6297.118403423525, 6308.3060863305, 6324.272708638566, 6222.937770622327, 6273.905891411632, 6274.811755647338, 6279.0, 6281.685915704196, 6102.4507794298725, 6137.438273608381, 6138.112525404833, 6203.348648391854, 6279.529035322278, 4953.0589188054155, 4973.980334031515, 4974.228994505857, 4995.625328100407, 5012.348394708737, 4481.7983190756495, 4490.708103973297, 4528.886777830515, 4531.116432295671, 4557.316402539645, 5782.074868021181, 5788.3343415506915, 5791.565632577489, 5807.88384232313, 5875.177475153482, 5138.486151384906, 6191.473724733448, 6244.906326405107, 6307.716152773044, 6317.715702793385, 4846.733429661332, 5755.8561035974435, 5763.4812866842185, 5808.709564521757, 5847.976604094267, 5454.481824226692, 5481.287432633732, 5499.0, 5500.58745594082, 5576.996140694633, 6239.594426362272, 6255.311915689362, 6293.496520278458, 6293.685315734287, 6325.399087086719, 6180.887440260796, 6200.7519699211525, 6260.843478912881, 6261.092141639528, 6266.467350275096, 6320.841978950379, 6348.143000695032, 6355.0, 6355.078513615592, 6359.236891573041, 6191.288001879841, 6192.442680158807, 6211.440970312694, 6257.749690012531, 6259.96870015647, 6141.393954534099, 6170.167027451416, 6210.906836397548, 6218.5647004711045, 6331.620102793847, 5504.972475137599, 5760.222369979999, 5998.0403135499855, 6103.05402662588, 6110.358412366638, 6046.7581296749395, 6082.878342433215, 6141.109539116893, 6144.754209831557, 6150.10823430609, 5845.766169353178, 5980.252468441445, 6012.789552365646, 6035.668038257938, 6058.697065146637, 6140.478059364826, 6167.167432396569, 6177.258728952374, 6179.134921110958, 6180.47465965398, 5781.277340332458, 5844.298684157929, 5848.736806843787, 6009.549283803644, 6104.26748790148], "value": 5718.885057907594, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [473.6968340262231, 505.58036829431575, 520.5445235419008, 527.7414067107118, 553.7867920850472, 31912.840435797818, 33812.32375352494, 42991.355129673044, 43960.780196098975, 44074.33888491675, 45440.091198176015, 45576.08847823041, 45605.0, 45762.542374576245, 45770.54229457705, 9651.131398174146, 9839.852402213937, 9842.704718858446, 9854.310198286123, 9859.21126309897, 8849.33629977751, 9064.954675226614, 11901.0, 12078.818817717742, 12086.637400877988, 12166.695832604168, 12207.511699532053, 12234.021278297714, 12245.93877030618, 12262.264264144136, 7573.697052117964, 9080.046595107484, 10237.539310731036, 12075.033997280196, 12223.327716975607, 8923.866142007875, 8979.640814367418, 11597.304161750237, 11713.121515886258, 11852.585159519376, 42789.0, 43150.56849431497, 43213.56786432127, 43214.0, 43269.35095973563, 3531.29374125176, 3610.115521697201, 3741.5136032315895, 4087.2234275487563, 4089.0, 32182.0, 33426.0, 33838.661613383796, 33857.66142338594, 34049.0, 3367.579052618423, 3370.679785420381, 3382.5940887093707, 3440.4839274108836, 3480.008197663679, 503276.0, 505148.0, 506422.0, 506478.0, 507755.0, 63266.683666581375, 63562.0, 64036.67981660062, 64124.358756412774, 64163.679181603795, 67138.0, 67377.32622673809, 67429.66285168641, 67435.66282168656, 67491.31271718148, 16472.670546589, 16967.236474358684, 16983.83016169847, 17007.0, 17024.404145854834, 11873.940630296793, 12196.451159697657, 12814.67963300925, 13039.022073344537, 13135.080544361803, 17557.473275801745, 17721.468355949342, 17788.0, 17811.46565603034, 17826.10869456548, 6066.939330606726, 7288.635568221568, 7295.416366690758, 7305.159906610702, 7340.889886651705, 937.5921474158763, 990.7027891632481, 1037.7457522906936, 1160.1762748448527, 1162.1342100135284, 31187.376252474824, 31280.843595781877, 31373.8431307842, 32187.51718724221, 32250.0, 916.9266458683289, 1018.5620183321095, 1027.7563799915542, 1131.321207275628, 1182.1606659272013, 16226.026438413734, 16236.75644865328, 16335.346586136659, 16341.754873676904, 16775.916120419563, 666174.6691266512, 679225.0, 680852.0, 686734.0, 687291.0, 62355.06467402993, 62533.0, 62617.37382626115, 62646.0, 62700.0, 1312.5734136405706, 1350.9527166549124, 1454.6363409147714, 1454.941802327895, 1488.0996996816987, 30766.154030758225, 32178.678213218504, 32849.34301314008, 32932.1766955828, 33175.36962013901, 53701.925961481335, 55255.72372138114, 55285.0, 55349.893002138924, 55522.444775551725, 503832.0, 628108.0, 629220.0, 629336.0, 629411.0, 38008.61991380051, 38784.2243155141, 46050.30924536067, 46072.46601436819, 46583.0, 50893.49106508888, 51417.22874156816, 52389.952200956526, 53855.461445385044, 54133.72933135467, 42012.57987420209, 42791.14417711566, 42961.0, 43265.567344326155, 43444.782776085915, 42321.94195145146, 42375.0, 42390.0, 43474.34788478112, 43597.56402435935, 40797.0, 41396.1720765577, 41583.7920810394, 41658.58341416547, 41665.0, 35164.64835351716, 35919.820400898876, 36242.27515449624, 36832.81583592065, 36938.63061369459, 26705.19884403471, 26732.866335668197, 26769.33076673099, 26831.0, 27163.456730865666], "value": 21477.47545008533, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.0806962419888, 385.65098585779873, 390.122224993764, 391.17852509729573, 411.0422715073878, 15157.317920693567, 15395.923020384897, 20934.89532552335, 21166.0, 21193.0, 20571.897140514313, 21038.474038149056, 21432.57134857302, 21928.232511862087, 21955.890220548914, 5302.867428314285, 5789.594728369016, 5997.37027612101, 6023.969880150593, 6056.545759068087, 5917.5561832862495, 5963.045912653976, 6011.759529618832, 6019.337872834007, 6027.246594175728, 5655.293088363955, 5879.323877754049, 5889.322727886283, 5922.378150294222, 5956.374580669032, 5633.15502674598, 5853.239078919756, 5887.35239123696, 5915.704214789243, 5945.108233764949, 5122.129238029506, 5134.666246693953, 5136.845894623168, 5154.046501397216, 5156.097682905514, 25889.611655825178, 26177.73822261791, 26227.868860655766, 26401.73598264012, 26700.33249168767, 2265.7054582904284, 2283.703118594589, 2309.122533437283, 2372.991478621579, 2501.8999240030557, 15421.614459638487, 16144.75782863258, 16281.348746050144, 16480.752788708178, 16771.83228167727, 1862.9254829806791, 1929.8456123510086, 1943.5918457124021, 1990.731251281073, 2066.8553201275918, 70701.99999999949, 112042.3193652096, 113978.0, 120976.0, 133816.99999999904, 8961.551922403854, 10780.197148741383, 10935.125189984783, 11110.111191104692, 11238.55045798159, 13666.726665466635, 13744.03791734569, 13769.655758606115, 13788.0, 13802.102863313852, 8512.06367299609, 8734.65061397537, 8789.516576588348, 8809.603567839462, 8831.381803273838, 8851.070637582961, 8864.0, 8876.911230887608, 8932.0, 9021.32340074497, 6618.735250590019, 6727.966360168168, 6727.966360168168, 6746.460283177334, 6754.527183097136, 1732.7227643576966, 1802.8737988340952, 1831.5238038110188, 1839.2826797548967, 1840.7883093444157, 770.5492287012161, 933.0343094896749, 1003.8945910679273, 1030.8402197659375, 1054.1935419404163, 20553.69169462459, 21734.45663858416, 22402.0, 22712.545749084926, 22740.317790466313, 967.8644989701447, 971.961121555144, 993.7913038261902, 1036.409246729357, 1109.7447587055024, 10084.49577521121, 11743.941280293544, 11884.702882428008, 11919.880801191877, 11923.105767067505, 540539.0, 548425.0, 594358.0, 597023.0, 616086.0, 102236.0, 121581.39209303897, 123997.0, 125139.0, 127166.72833271734, 1010.8281592129356, 1031.9432431216205, 1043.0664555223034, 1064.1274155192668, 1069.187417562658, 16849.494515164566, 19587.902060490178, 19708.40874773759, 19720.80279197247, 19795.604087917873, 52823.60469534345, 53043.734781325846, 53151.93696125979, 54888.45111548834, 55023.72488137534, 50246.995060099325, 71327.64336178286, 71416.57166856738, 73948.0, 74276.0, 7979.0, 8019.6391162398995, 8092.433529652868, 8163.83672326562, 8379.45533540318, 13329.0, 13369.933150334185, 13385.799213011618, 13593.660158496117, 13665.658358540719, 6617.7683781067435, 11463.426828658701, 11472.31166130035, 11578.594749183738, 11795.41022948832, 11478.713032174262, 11514.136439766884, 11518.712032199262, 11563.132765042823, 11747.765044698886, 11655.475503602189, 11842.526298947945, 11873.762524749629, 12038.0, 12045.457954392248, 7539.208383119693, 7857.253560911704, 7957.204279572228, 7987.520748755093, 8125.553094579855, 10555.68332949982, 10578.100861426865, 10688.732781680521, 10704.518296676513, 10949.616763413242], "value": 9802.141612834454, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1525.496586126578, 1566.8433156684332, 1754.7367894815777, 1925.451246394777, 1933.352326970465, 8531.616077276522, 8793.824123517526, 8973.955130224356, 8986.460812351264, 8994.0, 7875.4487185897015, 8087.433879628428, 8590.742277731662, 8824.249938755214, 8824.823503529926, 2365.6096744037254, 2397.328747950568, 2398.5083057973075, 2399.9880000599974, 2408.169181632334, 2354.1878052072007, 2361.0437772702085, 2401.7478164792706, 2404.555157295897, 2409.1567951217107, 2090.651529763302, 2183.759786423492, 2308.6537019447132, 2365.503244318695, 2370.3600027992434, 2301.8618882866913, 2307.042577330406, 2317.1310758465575, 2324.6861673674007, 2330.5338932213454, 2359.28041947206, 2369.9170529031576, 2372.7864492195613, 2375.7505461926594, 2376.964345534818, 8801.823963520694, 8862.734117976472, 8876.8668469973, 8880.866786998136, 8899.15458031486, 5221.451747566527, 5329.786808527693, 5376.301080859464, 5384.9461505385225, 5395.082835917903, 3634.6183650716557, 3636.272745450894, 3702.8148592570533, 3708.684761795251, 3721.665050145473, 1094.9616763413242, 1100.1418893263267, 1101.9228653994223, 1122.421952694363, 1123.6741345009937, 9935.956724543963, 11807.0, 11810.0, 11814.822777658343, 11816.0, 4546.113507866001, 4584.541545845389, 4770.403699537558, 4773.880652983634, 4775.474697783241, 10093.69718908434, 10121.342112762646, 10123.240756943258, 10128.291019628703, 10154.289199756095, 5367.5169234769055, 5376.462353764592, 5377.0321342158, 5380.704061276589, 5390.0, 4852.587530059914, 4876.756162191876, 4909.7790599423115, 4909.852704418873, 4943.826966056171, 3990.2817492850986, 4008.7594744315434, 4016.2369149861433, 4119.629233369011, 4189.937150942738, 1186.9287842729464, 1231.2920070959235, 1283.7881749511225, 1321.6563693439582, 1338.3174580963778, 671.3655595462338, 758.4501236603529, 824.5382585751998, 937.2736129499692, 978.5498670611456, 4995.650304478651, 5876.941230587725, 5932.377100404396, 6227.906581401283, 6332.0, 1019.3017782818793, 1029.3000759483473, 1056.2131212246773, 1077.8437126616755, 1089.4661615808354, 3970.8014599269923, 4022.2960981828064, 4117.21772863155, 4136.358864376027, 4180.581941805856, 8955.731328060168, 10445.321054131457, 10603.204759642904, 10716.517756700967, 10751.677449676523, 9751.561179746928, 10423.843642345371, 10578.682639520826, 10664.626738064131, 10691.144708423308, 8116.472429292077, 8424.873626895602, 8442.62008209632, 8451.915480845235, 8461.0, 6099.573029887865, 6225.688715564294, 6237.28271248794, 6323.08315294289, 6347.333529979285, 10250.077493025663, 10370.688879333633, 10372.74068148272, 10386.740331491774, 10396.376217426978, 10159.949200253952, 10186.643467478603, 10277.53751081218, 10373.688789336333, 10475.109615682752, 7045.682944267623, 7079.787606371817, 7173.928260717326, 7177.246389129066, 7184.030155928883, 8138.796530086795, 8319.0, 8386.52597483756, 8388.328933685412, 8390.790230244049, 5496.80761173357, 6772.492063095189, 7509.098908131059, 7521.5111017783665, 7584.061325568039, 7541.095068591804, 7545.170031296663, 7562.621868906643, 7698.191689872483, 7701.6534255957495, 7143.85712285741, 7149.213586505376, 7205.315495027964, 7216.5670059796585, 7226.277372262732, 5089.109405854035, 5108.540231379194, 5148.536631703165, 5175.14610089331, 5267.71027593486, 5058.772355243949, 5078.984203159457, 5079.161938280143, 5298.072837253542, 5299.602529810357], "value": 4642.880850216603, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.8049248480972, 306.77452072726555, 312.8983080498837, 320.94062598419293, 323.6666233779208, 5225.282852916491, 5349.598780091489, 5394.271773310602, 5433.755481003354, 5451.536619387357, 3905.17991221843, 4891.266310053494, 5174.249733788598, 5319.6808191508535, 5400.378956420013, 3251.8048917064934, 3733.8693145739903, 4079.653229475498, 4103.302438585448, 4120.979395103021, 3816.179521402898, 3927.8428862845594, 3974.503187101612, 4107.774072426016, 4187.60217779312, 3418.965810341902, 4057.8173982170874, 4067.0, 4083.0, 4113.177364527091, 3724.944125838115, 3839.2705385976574, 4087.632113109805, 4098.180363927211, 4143.399207114951, 2969.6436427628605, 3137.4823154179535, 3477.7043951264172, 3583.835253542583, 3596.4355505355256, 4016.196760647882, 4687.88280292992, 4941.776200709659, 4971.8259860905055, 5029.7736601853, 4315.784210789479, 4406.052698669801, 4606.884827879297, 4660.766961651938, 4661.207594708908, 5654.264945557093, 5675.829725108253, 5681.403452637455, 5746.626469279484, 5767.36558978512, 3601.94597081044, 3843.7117216208894, 3866.0, 3889.3193691104225, 3895.2599006188734, 4924.876878078041, 5541.251930989305, 5861.501772349313, 5903.144044113623, 5984.401559844068, 3944.802759862053, 3957.8021098944937, 3960.6039396060164, 3979.880603581897, 3986.3223252047223, 3832.425136229532, 3968.8015599220503, 3997.340438827619, 4009.91980160401, 4027.5771044039952, 4626.745528995937, 4630.0, 4657.0, 4673.158831410379, 4694.6009589185305, 5276.551493123051, 5752.9712351437975, 5777.844431113794, 5789.884202316014, 5841.88316233673, 1204.8132539456399, 1265.0, 1316.453671726242, 1373.7870630052357, 1405.2622373254123, 849.3332733804058, 938.7183844846519, 949.4018768176143, 961.053362437998, 966.6375109334, 4097.631213190767, 4240.681948853849, 4341.60925516705, 4371.0, 4383.035732138925, 3952.6442620164325, 4136.98643832263, 4325.307950727868, 4471.3679506979715, 4925.605951523869, 4278.0, 4347.326164444453, 4368.36658684489, 4384.364267181241, 4393.75834329115, 2286.8742219178107, 2448.571499987531, 2478.3060742991993, 2479.367761220877, 2504.5742223821994, 4504.211762941472, 4794.506856434635, 4982.701037937735, 5055.848324550269, 5081.390233171969, 4131.731437456556, 4447.465624359591, 4605.286180642006, 4880.219164933665, 5036.64743467961, 4331.5884990925815, 4672.159011377985, 4788.449328327218, 6088.299845517822, 6134.785282515001, 5851.97590421666, 5895.882082358243, 5898.587098903207, 5903.085021821625, 5914.58597898143, 4695.413073365829, 5019.548240658212, 5360.490753378423, 5388.191771234189, 5841.824745257649, 6003.279606447254, 6006.429389208019, 6029.216201893726, 6045.788397406246, 6075.210222671025, 3313.2334500691227, 3476.061463404917, 3854.5160113356, 3911.7677931451503, 3942.9408558872215, 5711.8857622848145, 5923.733431995484, 5935.643861368331, 5939.91090133657, 5979.641221526722, 4742.857714268577, 5698.660814708623, 5739.598228123991, 5761.39505351932, 5808.82573522795, 5044.646874718881, 5654.434556544313, 5726.2269593606, 5749.7125143741805, 5771.624844385102, 4929.028388644428, 4932.457429682804, 5590.385057643737, 5610.607257491936, 5671.291088613923, 5219.556337711337, 5632.662849605863, 5662.886742265049, 5763.6541807491685, 5767.971160144172, 4369.388285640077, 4915.5330243626795, 4970.304157417892, 5642.771105413257, 5651.7784862627905], "value": 3985.20655927705, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [658.9176352955881, 802.2178376083319, 837.1670188162778, 843.9156084391561, 869.7216890595008, 15151.545453636394, 16479.258433370498, 16567.42014029508, 17488.3004679813, 18256.0, 17110.230039648246, 17266.654666906656, 17283.222254998524, 17288.22203000865, 17440.127993600334, 707.2750430808437, 726.6766289001397, 877.9209871111599, 895.3150839607705, 898.1333013641835, 866.1511718515858, 896.5830888636783, 899.0, 900.2392977933636, 900.275278400887, 927.3184209605935, 931.9347645664806, 934.1965909317981, 937.8499440089587, 937.8921424036221, 733.0580204437326, 898.3172788680649, 923.2337160157053, 927.1192367251105, 932.1424289653535, 690.1407747354525, 690.8065741592362, 692.594832023267, 692.62944324786, 693.1647364925253, 15663.0, 15717.449889253821, 15736.0, 15736.527904162893, 15760.605984850356, 13793.379297931517, 13918.58244252674, 13971.161730296113, 14061.5078472254, 14138.646533836634, 12396.75206495874, 12917.031222658245, 13305.00212484058, 13509.189448633015, 13583.320833958263, 4560.908781824345, 4608.539146085398, 4621.584057434847, 4791.161546729343, 4807.18277892759, 102778.48610756898, 103308.96691033068, 103344.0, 104131.0, 106451.46774266157, 11053.336799792038, 11161.218714690056, 11480.712982175346, 11683.649490515298, 11798.233114847508, 22836.4290892729, 22964.770352296597, 23109.0, 23231.767682323298, 23256.76743232546, 8412.94838145232, 8427.915720842835, 8482.236598706146, 8527.786805329917, 8647.3946823723, 4579.702319349231, 4706.1528924793865, 4709.0, 4720.315554244616, 4881.974785295057, 3460.463628137643, 3594.712423006153, 3594.9101272468397, 3610.891673249807, 3818.5417749870194, 685.5509641185032, 689.334791925794, 700.5061431690646, 702.2766550453042, 710.0414440505276, 556.3824155187739, 637.9904301435482, 663.3466035954581, 678.3250665587772, 707.7239181899033, 9366.637374203841, 9590.760230994281, 9615.0, 9788.9021109788, 9829.950850245847, 607.4077774170237, 635.1358762033354, 642.601587016053, 670.7954074007474, 674.6120980436269, 3607.4408466687705, 4717.0, 4718.339432479455, 4724.622030237572, 4745.667803253808, 107000.71997120028, 123936.0, 127777.22227777148, 128694.0, 172506.0, 116994.41502792432, 118925.0, 119795.0, 122600.38699806444, 124768.75231247753, 75427.0, 77692.83460748206, 109639.0, 110291.0, 114700.0, 9974.351667141613, 10169.542370593193, 10241.027102425258, 10272.948635256776, 10296.330738501973, 57311.85376292534, 61563.38436615756, 62057.379426206964, 63551.0, 66589.0, 64845.67577162084, 74846.0, 82884.58557707173, 89655.65516517388, 95030.52484737532, 14537.200453975132, 14615.707685846437, 14866.702665946837, 14977.0, 15313.387464501295, 15517.095737121746, 15707.921460392625, 15930.761038584198, 16145.515634530982, 16215.945963512333, 14436.422543098159, 14675.19286439256, 14751.852481475476, 14797.556073317817, 14922.552323430315, 14451.27743612794, 14679.41282348694, 14683.41266349334, 15151.393944242547, 15169.696606068037, 13788.0, 14001.159930404208, 14200.78698819498, 14362.640933976734, 14389.42442302338, 11312.15158863072, 11567.595134170264, 11716.94141529321, 11784.4696988634, 11820.881791181979, 11031.282966607145, 11159.776804464027, 11484.655460336204, 11758.529658813894, 11782.293062416282], "value": 8106.537644365522, "unit": "score"}], "name": "dromaeo_css", "value": 7518.3617448594805, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 111.0, 114.0, 112.0, 113.0, 111.0, 160.0, 112.0, 112.0, 111.0], "value": 115.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 108.0, 104.0, 183.0, 139.0, 138.0, 108.0, 127.0, 145.0, 104.0], "value": 129.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 185.0, 186.0, 182.0, 181.0, 197.0, 264.0, 183.0, 184.0], "value": 193.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 58.0, 106.0, 59.0, 72.0, 84.0, 85.0, 89.0, 49.0], "value": 75.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 92.0, 71.0, 69.0, 68.0, 97.0, 70.0, 68.0, 100.0], "value": 81.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 88.0, 84.0, 87.0, 84.0, 88.0, 84.0, 87.0, 84.0], "value": 85.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 93.0, 92.0, 93.0, 93.0, 92.0, 92.0, 92.0], "value": 92.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 87.0, 83.0, 84.0, 82.0, 85.0, 85.0, 84.0, 86.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 54.0, 53.0, 54.0, 54.0, 56.0, 54.0, 53.0, 55.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 69.0, 70.0, 77.0, 72.0, 71.0, 70.0, 70.0, 69.0, 71.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 82.0, 82.0, 83.0, 84.0, 79.0, 79.0, 81.0, 83.0, 83.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 135.0, 135.0, 136.0, 134.0, 134.0, 136.0, 133.0, 185.0, 128.0], "value": 139.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 165.0, 162.0, 161.0, 163.0, 161.0, 160.0, 163.0, 162.0, 164.0], "value": 162.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 66.0, 67.0, 66.0, 65.0, 66.0, 64.0, 65.0], "value": 65.7, "unit": "score"}], "name": "kraken", "value": 1431.1, "alertThreshold": 2.0}]} 11:13:19 INFO - Return code: 0 11:13:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:13:19 INFO - # TBPL SUCCESS # 11:13:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:13:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:13:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:13:19 INFO - 2016-07-15 11:13:19.543 screenresolution[1688:16031] starting screenresolution argv=screenresolution get 11:13:19 INFO - 2016-07-15 11:13:19.545 screenresolution[1688:16031] Display 0: 1600x1200x32@60 11:13:19 INFO - 2016-07-15 11:13:19.551 screenresolution[1689:16034] starting screenresolution argv=screenresolution list 11:13:19 INFO - Available Modes on Display 0 11:13:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:13:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:13:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:13:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:13:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:13:19 INFO - Intel Iris: 11:13:19 INFO - Chipset Model: Intel Iris 11:13:19 INFO - Type: GPU 11:13:19 INFO - Bus: Built-In 11:13:19 INFO - VRAM (Dynamic, Max): 1536 MB 11:13:19 INFO - Vendor: Intel (0x8086) 11:13:19 INFO - Device ID: 0x0a2e 11:13:19 INFO - Revision ID: 0x0009 11:13:19 INFO - Displays: 11:13:19 INFO - Display: 11:13:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:13:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:13:19 INFO - Display Serial Number: 1600x1200 60 11:13:19 INFO - Main Display: Yes 11:13:19 INFO - Mirror: Off 11:13:19 INFO - Online: Yes 11:13:19 INFO - Rotation: Supported 11:13:19 INFO - Return code: 0 11:13:19 INFO - Running post-action listener: _resource_record_post_action 11:13:19 INFO - ##### 11:13:19 INFO - ##### Finished run-tests step (success) 11:13:19 INFO - ##### 11:13:19 INFO - Running post-run listener: _resource_record_post_run 11:13:19 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 31739392; Write bytes: 705996800; Read time: 196; Write time: 1935 11:13:19 INFO - TinderboxPrint: CPU usage
53.4% 11:13:19 INFO - TinderboxPrint: I/O read bytes / time
31,739,392 / 196 11:13:19 INFO - TinderboxPrint: I/O write bytes / time
705,996,800 / 1,935 11:13:19 INFO - TinderboxPrint: CPU idle
2,254.4 (46.6%) 11:13:19 INFO - TinderboxPrint: CPU system
959.3 (19.8%) 11:13:19 INFO - TinderboxPrint: CPU user
1,627.9 (33.6%) 11:13:19 INFO - TinderboxPrint: Swap in / out
434,573,312 / 0 11:13:19 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 403124736; Write bytes: 397563904; Read time: 29731; Write time: 805 11:13:19 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 29675008; Write bytes: 294744064; Read time: 178; Write time: 1094 11:13:20 INFO - Running post-run listener: _upload_blobber_files 11:13:20 INFO - Blob upload gear active. 11:13:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:13:20 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:13:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:13:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:13:20 INFO - (blobuploader) - INFO - Open directory for files ... 11:13:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:13:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:13:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:13:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:13:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:13:21 INFO - (blobuploader) - INFO - Done attempting. 11:13:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:13:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:13:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:13:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:13:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:13:22 INFO - (blobuploader) - INFO - Done attempting. 11:13:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:13:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:13:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:13:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:13:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:13:24 INFO - (blobuploader) - INFO - Done attempting. 11:13:24 INFO - (blobuploader) - INFO - Iteration through files over. 11:13:24 INFO - Return code: 0 11:13:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:13:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:13:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/459a5f6b989c85149e8f379ca30006bf43659f148760e8f58ab5c0833749bfc2059ea8fde8be2de56478ec0fb7abe9e81c8414830cbc5c4e62e2080e2bfd496b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51e8cb170ad998275045edb4ad76106e550a82dd4794ac22dd5ab52d320c2621a69e843cd26d5da6121dc5d467f2bbbcea8927169fdb7e579fe3bdbcddea0fae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bba85951a34f80a56cc0af2df29e78e3b6a1efc4ab96c96de26d92b9d1c39e33a6b872f64df7f6d1a7b209f9c848b06263de7f20925c74b233be40d1ad697114"} 11:13:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:13:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:13:24 INFO - Contents: 11:13:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/459a5f6b989c85149e8f379ca30006bf43659f148760e8f58ab5c0833749bfc2059ea8fde8be2de56478ec0fb7abe9e81c8414830cbc5c4e62e2080e2bfd496b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51e8cb170ad998275045edb4ad76106e550a82dd4794ac22dd5ab52d320c2621a69e843cd26d5da6121dc5d467f2bbbcea8927169fdb7e579fe3bdbcddea0fae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bba85951a34f80a56cc0af2df29e78e3b6a1efc4ab96c96de26d92b9d1c39e33a6b872f64df7f6d1a7b209f9c848b06263de7f20925c74b233be40d1ad697114"} 11:13:24 INFO - Running post-run listener: copy_logs_to_upload_dir 11:13:24 INFO - Copying logs to upload dir... 11:13:24 INFO - mkdir: /builds/slave/test/build/upload/logs 11:13:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.918543 ========= master_lag: 0.36 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-07-15 11:13:24.862946) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 11:13:24.871455) ========= 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.UccTJiasXS/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.Nwgj9pa0m2/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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/459a5f6b989c85149e8f379ca30006bf43659f148760e8f58ab5c0833749bfc2059ea8fde8be2de56478ec0fb7abe9e81c8414830cbc5c4e62e2080e2bfd496b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51e8cb170ad998275045edb4ad76106e550a82dd4794ac22dd5ab52d320c2621a69e843cd26d5da6121dc5d467f2bbbcea8927169fdb7e579fe3bdbcddea0fae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bba85951a34f80a56cc0af2df29e78e3b6a1efc4ab96c96de26d92b9d1c39e33a6b872f64df7f6d1a7b209f9c848b06263de7f20925c74b233be40d1ad697114"} build_url:https://queue.taskcluster.net/v1/task/VgozL2rOSqO8xALLY6zy9g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010974 build_url: 'https://queue.taskcluster.net/v1/task/VgozL2rOSqO8xALLY6zy9g/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/459a5f6b989c85149e8f379ca30006bf43659f148760e8f58ab5c0833749bfc2059ea8fde8be2de56478ec0fb7abe9e81c8414830cbc5c4e62e2080e2bfd496b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51e8cb170ad998275045edb4ad76106e550a82dd4794ac22dd5ab52d320c2621a69e843cd26d5da6121dc5d467f2bbbcea8927169fdb7e579fe3bdbcddea0fae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bba85951a34f80a56cc0af2df29e78e3b6a1efc4ab96c96de26d92b9d1c39e33a6b872f64df7f6d1a7b209f9c848b06263de7f20925c74b233be40d1ad697114"}' ========= master_lag: 0.58 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 11:13:25.464308) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 11:13:25.464623) ========= 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.UccTJiasXS/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.Nwgj9pa0m2/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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.005290 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 11:13:25.513648) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 11:13:25.513950) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 11:13:25.515299) ========= ========= Total master_lag: 1.06 =========