builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0016 starttime: 1467149470.24 results: success (0) buildid: 20160628120923 builduid: 89b84f7ce027403ba8c7a69fecb4aec0 revision: a5f7ad06f03b9ae9ea2ab8e06f89ef67e413ede9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-28 14:31:10.240476) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-28 14:31:10.240989) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-28 14:31:10.241325) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-28 14:31:10.268090) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 14:31:10.268798) ========= 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.StQFBnmBcF/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.dfuHpyTCH8/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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-06-28 14:31:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.61M=0.001s 2016-06-28 14:31:10 (8.61 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.081324 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 14:31:10.377171) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 14:31:10.377610) ========= 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.StQFBnmBcF/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.dfuHpyTCH8/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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.020482 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 14:31:10.427026) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-06-28 14:31:10.427443) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev a5f7ad06f03b9ae9ea2ab8e06f89ef67e413ede9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev a5f7ad06f03b9ae9ea2ab8e06f89ef67e413ede9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.StQFBnmBcF/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.dfuHpyTCH8/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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-06-28 14:31:10,504 truncating revision to first 12 chars 2016-06-28 14:31:10,504 Setting DEBUG logging. 2016-06-28 14:31:10,504 attempt 1/10 2016-06-28 14:31:10,504 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/a5f7ad06f03b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-28 14:31:12,050 unpacking tar archive at: autoland-a5f7ad06f03b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.807948 ========= master_lag: 3.84 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-06-28 14:31:16.080244) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-28 14:31:16.080567) ========= script_repo_revision: a5f7ad06f03b9ae9ea2ab8e06f89ef67e413ede9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-28 14:31:16.085061) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-28 14:31:16.085339) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-28 14:31:16.116453) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 20 secs) (at 2016-06-28 14:31:16.116853) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.StQFBnmBcF/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.dfuHpyTCH8/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 14:31:16 INFO - MultiFileLogger online at 20160628 14:31:16 in /builds/slave/test 14:31:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 14:31:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:31:16 INFO - {'append_to_log': False, 14:31:16 INFO - 'base_work_dir': '/builds/slave/test', 14:31:16 INFO - 'blob_upload_branch': 'Autoland', 14:31:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:31:16 INFO - 'branch': 'Autoland', 14:31:16 INFO - 'buildbot_json_path': 'buildprops.json', 14:31:16 INFO - 'config_files': ('talos/mac_config.py',), 14:31:16 INFO - 'default_actions': ('clobber', 14:31:16 INFO - 'read-buildbot-config', 14:31:16 INFO - 'download-and-extract', 14:31:16 INFO - 'populate-webroot', 14:31:16 INFO - 'create-virtualenv', 14:31:16 INFO - 'install', 14:31:16 INFO - 'run-tests'), 14:31:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:31:16 INFO - 'download_minidump_stackwalk': True, 14:31:16 INFO - 'download_symbols': 'ondemand', 14:31:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:31:16 INFO - 'tooltool.py': '/tools/tooltool.py', 14:31:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:31:16 INFO - '/tools/misc-python/virtualenv.py')}, 14:31:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:31:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:31:16 INFO - 'installer_path': 'installer.exe', 14:31:16 INFO - 'log_level': 'info', 14:31:16 INFO - 'log_name': 'talos', 14:31:16 INFO - 'log_to_console': True, 14:31:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:31:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:31:16 INFO - 'opt_config_files': (), 14:31:16 INFO - 'pip_index': False, 14:31:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:31:16 INFO - 'cmd': ('bash', 14:31:16 INFO - '-c', 14:31:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:31:16 INFO - 'enabled': True, 14:31:16 INFO - 'halt_on_failure': False, 14:31:16 INFO - 'name': 'check_screen_resolution'},), 14:31:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:31:16 INFO - 'cmd': ('bash', 14:31:16 INFO - '-c', 14:31:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:31:16 INFO - 'enabled': True, 14:31:16 INFO - 'halt_on_failure': False, 14:31:16 INFO - 'name': 'check_screen_resolution'},), 14:31:16 INFO - 'run_cmd_checks_enabled': True, 14:31:16 INFO - 'sps_profile': False, 14:31:16 INFO - 'sps_profile_interval': 0, 14:31:16 INFO - 'suite': 'dromaeojs', 14:31:16 INFO - 'system_bits': '32', 14:31:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:31:16 INFO - 'title': 't-yosemite-r7-0016', 14:31:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:31:16 INFO - 'use_talos_json': True, 14:31:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:31:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:31:16 INFO - 'work_dir': 'build'} 14:31:16 INFO - ##### 14:31:16 INFO - ##### Running clobber step. 14:31:16 INFO - ##### 14:31:16 INFO - Running pre-action listener: _resource_record_pre_action 14:31:16 INFO - Running main action method: clobber 14:31:16 INFO - rmtree: /builds/slave/test/build 14:31:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:31:17 INFO - Running post-action listener: _resource_record_post_action 14:31:17 INFO - ##### 14:31:17 INFO - ##### Finished clobber step (success) 14:31:17 INFO - ##### 14:31:17 INFO - ##### 14:31:17 INFO - ##### Running read-buildbot-config step. 14:31:17 INFO - ##### 14:31:17 INFO - Running pre-action listener: _resource_record_pre_action 14:31:17 INFO - Running main action method: read_buildbot_config 14:31:17 INFO - Using buildbot properties: 14:31:17 INFO - { 14:31:17 INFO - "project": "", 14:31:17 INFO - "product": "firefox", 14:31:17 INFO - "script_repo_revision": "production", 14:31:17 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 14:31:17 INFO - "repository": "", 14:31:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 14:31:17 INFO - "buildid": "20160628120923", 14:31:17 INFO - "pgo_build": "False", 14:31:17 INFO - "basedir": "/builds/slave/test", 14:31:17 INFO - "buildnumber": 5, 14:31:17 INFO - "slavename": "t-yosemite-r7-0016", 14:31:17 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 14:31:17 INFO - "platform": "yosemite_r7", 14:31:17 INFO - "scheduler": "tests-autoland-macosx64-talos", 14:31:17 INFO - "branch": "autoland", 14:31:17 INFO - "repo_path": "integration/autoland", 14:31:17 INFO - "revision": "a5f7ad06f03b9ae9ea2ab8e06f89ef67e413ede9", 14:31:17 INFO - "stage_platform": "macosx64", 14:31:17 INFO - "builduid": "89b84f7ce027403ba8c7a69fecb4aec0", 14:31:17 INFO - "slavebuilddir": "test" 14:31:17 INFO - } 14:31:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 14:31:17 INFO - Running post-action listener: _resource_record_post_action 14:31:17 INFO - ##### 14:31:17 INFO - ##### Finished read-buildbot-config step (success) 14:31:17 INFO - ##### 14:31:17 INFO - ##### 14:31:17 INFO - ##### Running download-and-extract step. 14:31:17 INFO - ##### 14:31:17 INFO - Running pre-action listener: _resource_record_pre_action 14:31:17 INFO - Running main action method: download_and_extract 14:31:17 INFO - mkdir: /builds/slave/test/build/tests 14:31:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:31:17 INFO - https://queue.taskcluster.net/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 14:31:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 14:31:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 14:31:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 14:31:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 14:31:18 INFO - Downloaded 1199 bytes. 14:31:18 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 14:31:18 INFO - Using the following test package requirements: 14:31:18 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 14:31:18 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 14:31:18 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 14:31:18 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 14:31:18 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 14:31:18 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 14:31:18 INFO - u'jsshell-mac.zip'], 14:31:18 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 14:31:18 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 14:31:18 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 14:31:18 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 14:31:18 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 14:31:18 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 14:31:18 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 14:31:18 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 14:31:18 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 14:31:18 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 14:31:18 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 14:31:18 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:31:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:31:18 INFO - https://queue.taskcluster.net/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:31:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 14:31:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 14:31:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 14:31:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:32:25 INFO - Downloaded 18786910 bytes. 14:32:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:32:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:32:28 INFO - Return code: 0 14:32:28 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:32:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:32:28 INFO - https://queue.taskcluster.net/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:32:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 14:32:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 14:32:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 14:32:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:32:32 INFO - Downloaded 18786910 bytes. 14:32:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:32:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:32:35 INFO - Return code: 0 14:32:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:32:35 INFO - https://queue.taskcluster.net/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 14:32:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 14:32:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 14:32:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 14:32:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:33:44 INFO - Downloaded 14405245 bytes. 14:33:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:33:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 14:33:45 INFO - Return code: 0 14:33:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:33:45 INFO - https://queue.taskcluster.net/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 14:33:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 14:33:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 14:33:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 14:33:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 14:33:48 INFO - Downloaded 112359223 bytes. 14:33:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 14:33:48 INFO - mkdir: /builds/slave/test/properties 14:33:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:33:48 INFO - Writing to file /builds/slave/test/properties/build_url 14:33:48 INFO - Contents: 14:33:48 INFO - build_url:https://queue.taskcluster.net/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 14:33:50 INFO - Running post-action listener: _resource_record_post_action 14:33:50 INFO - Running post-action listener: set_extra_try_arguments 14:33:50 INFO - ##### 14:33:50 INFO - ##### Finished download-and-extract step (success) 14:33:50 INFO - ##### 14:33:50 INFO - ##### 14:33:50 INFO - ##### Running populate-webroot step. 14:33:50 INFO - ##### 14:33:50 INFO - Running pre-action listener: _resource_record_pre_action 14:33:50 INFO - Running main action method: populate_webroot 14:33:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:33:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:33:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:33:50 INFO - '--tppagecycles', 14:33:50 INFO - '7'], 14:33:50 INFO - 'tests': ['tsvgm']}}, 14:33:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:33:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:33:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:33:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:33:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:33:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:33:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:33:50 INFO - 'talos_options': ['--disable-e10s'], 14:33:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:33:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:33:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:33:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:33:50 INFO - 'talos_options': ['--disable-e10s'], 14:33:50 INFO - 'tests': ['damp', 'tps']}, 14:33:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:33:50 INFO - 'tests': ['damp', 'tps']}, 14:33:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:33:50 INFO - 'tests': ['dromaeo_dom']}, 14:33:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:33:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:33:50 INFO - 'tests': ['basic_compositor_video']}, 14:33:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:33:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:33:50 INFO - 'tests': ['a11yr', 14:33:50 INFO - 'ts_paint', 14:33:50 INFO - 'tpaint', 14:33:50 INFO - 'sessionrestore', 14:33:50 INFO - 'sessionrestore_no_auto_restore', 14:33:50 INFO - 'tabpaint']}, 14:33:50 INFO - 'other-e10s': {'tests': ['a11yr', 14:33:50 INFO - 'ts_paint', 14:33:50 INFO - 'tpaint', 14:33:50 INFO - 'sessionrestore', 14:33:50 INFO - 'sessionrestore_no_auto_restore', 14:33:50 INFO - 'tabpaint']}, 14:33:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:33:50 INFO - 'ts_paint', 14:33:50 INFO - 'tpaint', 14:33:50 INFO - 'sessionrestore', 14:33:50 INFO - 'sessionrestore_no_auto_restore', 14:33:50 INFO - 'tabpaint']}, 14:33:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:33:50 INFO - 'ts_paint', 14:33:50 INFO - 'tpaint', 14:33:50 INFO - 'sessionrestore', 14:33:50 INFO - 'sessionrestore_no_auto_restore', 14:33:50 INFO - 'tabpaint']}, 14:33:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:33:50 INFO - 'tests': ['a11yr', 14:33:50 INFO - 'ts_paint', 14:33:50 INFO - 'tpaint', 14:33:50 INFO - 'sessionrestore', 14:33:50 INFO - 'sessionrestore_no_auto_restore', 14:33:50 INFO - 'tabpaint']}, 14:33:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:33:50 INFO - 'tests': ['a11yr', 14:33:50 INFO - 'ts_paint', 14:33:50 INFO - 'tpaint', 14:33:50 INFO - 'sessionrestore', 14:33:50 INFO - 'sessionrestore_no_auto_restore', 14:33:50 INFO - 'tabpaint']}, 14:33:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:33:50 INFO - 'tests': ['tsvgx', 14:33:50 INFO - 'tsvgr_opacity', 14:33:50 INFO - 'tart', 14:33:50 INFO - 'tscrollx', 14:33:50 INFO - 'cart']}, 14:33:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:33:50 INFO - 'tsvgr_opacity', 14:33:50 INFO - 'tart', 14:33:50 INFO - 'tscrollx', 14:33:50 INFO - 'cart']}, 14:33:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:33:50 INFO - 'talos_options': ['--disable-e10s'], 14:33:50 INFO - 'tests': ['tp5o']}, 14:33:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:33:50 INFO - 'tests': ['tp5o']}, 14:33:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:33:50 INFO - 'talos_options': ['--disable-e10s', 14:33:50 INFO - '--xperf_path', 14:33:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:33:50 INFO - 'tests': ['tp5n']}, 14:33:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:33:50 INFO - 'talos_options': ['--xperf_path', 14:33:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:33:50 INFO - 'tests': ['tp5n']}}, 14:33:50 INFO - 'talos.zip': {'path': '', 14:33:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:33:50 INFO - Running post-action listener: _resource_record_post_action 14:33:50 INFO - ##### 14:33:50 INFO - ##### Finished populate-webroot step (success) 14:33:50 INFO - ##### 14:33:50 INFO - ##### 14:33:50 INFO - ##### Running create-virtualenv step. 14:33:50 INFO - ##### 14:33:50 INFO - Running pre-action listener: _resource_record_pre_action 14:33:50 INFO - Running main action method: create_virtualenv 14:33:50 INFO - Creating virtualenv /builds/slave/test/build/venv 14:33:50 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:33:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:33:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:33:50 INFO - Using real prefix '/tools/python27' 14:33:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:33:50 INFO - Installing distribute.............................................................................................................................................................................................done. 14:33:53 INFO - Installing pip.................done. 14:33:53 INFO - Return code: 0 14:33:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:33: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')]} 14:33:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33: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')]} 14:33:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e4dea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e573618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7f8c84700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e442d30>, '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 0x10e1a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e43ab20>, '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.StQFBnmBcF/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.dfuHpyTCH8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:33: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', 'pip>=1.5'] in /builds/slave/test/build 14:33: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 pip>=1.5 14:33:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StQFBnmBcF/Render', 14:33:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:33:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:33:53 INFO - 'HOME': '/Users/cltbld', 14:33:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:33:53 INFO - 'LOGNAME': 'cltbld', 14:33:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:53 INFO - 'MOZ_NO_REMOTE': '1', 14:33:53 INFO - 'NO_EM_RESTART': '1', 14:33:53 INFO - 'PAGER': '/bin/cat', 14:33:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:33:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:53 INFO - 'PWD': '/builds/slave/test', 14:33:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:33:53 INFO - 'SHELL': '/bin/bash', 14:33:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dfuHpyTCH8/Listeners', 14:33:53 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 14:33:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:33:53 INFO - 'USER': 'cltbld', 14:33:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:33:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:33:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:53 INFO - 'XPC_FLAGS': '0x0', 14:33:53 INFO - 'XPC_SERVICE_NAME': '0', 14:33:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:33:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:53 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:33:53 INFO - Cleaning up... 14:33:53 INFO - Return code: 0 14:33:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:33: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')]} 14:33:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33: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')]} 14:33:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33: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', '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 0x10e4dea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e573618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7f8c84700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e442d30>, '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 0x10e1a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e43ab20>, '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.StQFBnmBcF/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.dfuHpyTCH8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:33: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', 'psutil>=3.1.1'] in /builds/slave/test/build 14:33: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 psutil>=3.1.1 14:33:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StQFBnmBcF/Render', 14:33:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:33:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:33:54 INFO - 'HOME': '/Users/cltbld', 14:33:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:33:54 INFO - 'LOGNAME': 'cltbld', 14:33:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:54 INFO - 'MOZ_NO_REMOTE': '1', 14:33:54 INFO - 'NO_EM_RESTART': '1', 14:33:54 INFO - 'PAGER': '/bin/cat', 14:33:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:33:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:54 INFO - 'PWD': '/builds/slave/test', 14:33:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:33:54 INFO - 'SHELL': '/bin/bash', 14:33:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dfuHpyTCH8/Listeners', 14:33:54 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 14:33:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:33:54 INFO - 'USER': 'cltbld', 14:33:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:33:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:33:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:54 INFO - 'XPC_FLAGS': '0x0', 14:33:54 INFO - 'XPC_SERVICE_NAME': '0', 14:33:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:33:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:54 INFO - Downloading/unpacking psutil>=3.1.1 14:33:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:33:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:33:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:33:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:33:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:33:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:33:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:33:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:33:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:33:57 INFO - Installing collected packages: psutil 14:33:57 INFO - Running setup.py install for psutil 14:33:57 INFO - building 'psutil._psutil_osx' extension 14:33:57 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 14:33:57 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 14:33:57 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 14:33:58 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 14:33:58 INFO - building 'psutil._psutil_posix' extension 14:33:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 14:33:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:33:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:33:58 INFO - ^ 14:33:58 INFO - 1 warning generated. 14:33:58 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 14:33:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:33:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:33:58 INFO - Successfully installed psutil 14:33:58 INFO - Cleaning up... 14:33:58 INFO - Return code: 0 14:33:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:33: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')]} 14:33:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33: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')]} 14:33:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e4dea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e573618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7f8c84700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e442d30>, '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 0x10e1a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e43ab20>, '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.StQFBnmBcF/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.dfuHpyTCH8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:33:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:33:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:33:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StQFBnmBcF/Render', 14:33:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:33:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:33:58 INFO - 'HOME': '/Users/cltbld', 14:33:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:33:58 INFO - 'LOGNAME': 'cltbld', 14:33:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:58 INFO - 'MOZ_NO_REMOTE': '1', 14:33:58 INFO - 'NO_EM_RESTART': '1', 14:33:58 INFO - 'PAGER': '/bin/cat', 14:33:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:33:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:58 INFO - 'PWD': '/builds/slave/test', 14:33:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:33:58 INFO - 'SHELL': '/bin/bash', 14:33:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dfuHpyTCH8/Listeners', 14:33:58 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 14:33:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:33:58 INFO - 'USER': 'cltbld', 14:33:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:33:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:33:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:58 INFO - 'XPC_FLAGS': '0x0', 14:33:58 INFO - 'XPC_SERVICE_NAME': '0', 14:33:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:33:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:33:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:33:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:33:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:33:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:33:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:33:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:34:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:34:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:34:01 INFO - Installing collected packages: mozsystemmonitor 14:34:01 INFO - Running setup.py install for mozsystemmonitor 14:34:01 INFO - Successfully installed mozsystemmonitor 14:34:01 INFO - Cleaning up... 14:34:01 INFO - Return code: 0 14:34:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:34:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:34:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:34:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:01 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 0x10e4dea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e573618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7f8c84700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e442d30>, '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 0x10e1a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e43ab20>, '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.StQFBnmBcF/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.dfuHpyTCH8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:34:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:34:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:34:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StQFBnmBcF/Render', 14:34:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:01 INFO - 'HOME': '/Users/cltbld', 14:34:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:01 INFO - 'LOGNAME': 'cltbld', 14:34:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:01 INFO - 'MOZ_NO_REMOTE': '1', 14:34:01 INFO - 'NO_EM_RESTART': '1', 14:34:01 INFO - 'PAGER': '/bin/cat', 14:34:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:01 INFO - 'PWD': '/builds/slave/test', 14:34:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:01 INFO - 'SHELL': '/bin/bash', 14:34:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dfuHpyTCH8/Listeners', 14:34:01 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 14:34:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:01 INFO - 'USER': 'cltbld', 14:34:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:01 INFO - 'XPC_FLAGS': '0x0', 14:34:01 INFO - 'XPC_SERVICE_NAME': '0', 14:34:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:01 INFO - Downloading/unpacking jsonschema==2.5.1 14:34:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:34:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:34: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 14:34: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 14:34: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 14:34: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 14:34: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 14:34: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 14:34:04 INFO - Downloading functools32-3.2.3-2.tar.gz 14:34:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:34:04 INFO - warning: no files found matching '*.txt' 14:34:04 INFO - no previously-included directories found matching 'build' 14:34:04 INFO - no previously-included directories found matching 'dist' 14:34:04 INFO - no previously-included directories found matching '.git*' 14:34:04 INFO - Installing collected packages: jsonschema, functools32 14:34:04 INFO - Running setup.py install for functools32 14:34:04 INFO - warning: no files found matching '*.txt' 14:34:04 INFO - no previously-included directories found matching 'build' 14:34:04 INFO - no previously-included directories found matching 'dist' 14:34:04 INFO - no previously-included directories found matching '.git*' 14:34:04 INFO - Successfully installed jsonschema functools32 14:34:04 INFO - Cleaning up... 14:34:04 INFO - Return code: 0 14:34:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:34:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:34:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:34:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:04 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 0x10e4dea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e573618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7f8c84700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e442d30>, '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 0x10e1a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e43ab20>, '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.StQFBnmBcF/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.dfuHpyTCH8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:34:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:34:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:34:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StQFBnmBcF/Render', 14:34:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:04 INFO - 'HOME': '/Users/cltbld', 14:34:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:04 INFO - 'LOGNAME': 'cltbld', 14:34:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:04 INFO - 'MOZ_NO_REMOTE': '1', 14:34:04 INFO - 'NO_EM_RESTART': '1', 14:34:04 INFO - 'PAGER': '/bin/cat', 14:34:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:04 INFO - 'PWD': '/builds/slave/test', 14:34:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:04 INFO - 'SHELL': '/bin/bash', 14:34:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dfuHpyTCH8/Listeners', 14:34:04 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 14:34:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:04 INFO - 'USER': 'cltbld', 14:34:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:04 INFO - 'XPC_FLAGS': '0x0', 14:34:04 INFO - 'XPC_SERVICE_NAME': '0', 14:34:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:05 INFO - Downloading/unpacking blobuploader==1.2.4 14:34:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:07 INFO - Downloading blobuploader-1.2.4.tar.gz 14:34:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:34:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:34:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:34:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:34:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:08 INFO - Downloading docopt-0.6.1.tar.gz 14:34:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:34:08 INFO - Installing collected packages: blobuploader, requests, docopt 14:34:08 INFO - Running setup.py install for blobuploader 14:34:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:34:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:34:08 INFO - Running setup.py install for requests 14:34:09 INFO - Running setup.py install for docopt 14:34:09 INFO - Successfully installed blobuploader requests docopt 14:34:09 INFO - Cleaning up... 14:34:09 INFO - Return code: 0 14:34:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:34:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:34:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:34:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:09 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 0x10e4dea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e573618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7f8c84700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e442d30>, '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 0x10e1a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e43ab20>, '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.StQFBnmBcF/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.dfuHpyTCH8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:34:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:34:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:34:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StQFBnmBcF/Render', 14:34:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:09 INFO - 'HOME': '/Users/cltbld', 14:34:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:09 INFO - 'LOGNAME': 'cltbld', 14:34:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:09 INFO - 'MOZ_NO_REMOTE': '1', 14:34:09 INFO - 'NO_EM_RESTART': '1', 14:34:09 INFO - 'PAGER': '/bin/cat', 14:34:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:09 INFO - 'PWD': '/builds/slave/test', 14:34:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:09 INFO - 'SHELL': '/bin/bash', 14:34:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dfuHpyTCH8/Listeners', 14:34:09 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 14:34:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:09 INFO - 'USER': 'cltbld', 14:34:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:09 INFO - 'XPC_FLAGS': '0x0', 14:34:09 INFO - 'XPC_SERVICE_NAME': '0', 14:34:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:34:09 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-rzz6iu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:34:09 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-LVcWUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:34:10 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-amXArW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:34:10 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-R8cLUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:34:10 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-R1lJKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:34:10 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-kB8wK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:34:10 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-XtURAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:34:10 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-aXpWRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:34:10 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-sLEUd2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:34:10 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-jX1Ti1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:34:10 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-OiWfam-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:34:10 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-HNTJ4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:34:11 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-RN9xTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:34:11 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-PPypQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:34:11 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-Fg8_gG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:34:11 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-hnPGko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:34:11 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-x_wrkG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:34:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:34:11 INFO - Running setup.py install for manifestparser 14:34:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:34:11 INFO - Running setup.py install for mozcrash 14:34:11 INFO - Running setup.py install for mozdebug 14:34:12 INFO - Running setup.py install for mozdevice 14:34:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:34:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:34:12 INFO - Running setup.py install for mozfile 14:34:12 INFO - Running setup.py install for mozhttpd 14:34:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:34:12 INFO - Running setup.py install for mozinfo 14:34:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:34:12 INFO - Running setup.py install for mozInstall 14:34:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:34:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:34:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:34:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:34:12 INFO - Running setup.py install for mozleak 14:34:12 INFO - Running setup.py install for mozlog 14:34:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:34:13 INFO - Running setup.py install for moznetwork 14:34:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:34:13 INFO - Running setup.py install for mozprocess 14:34:13 INFO - Running setup.py install for mozprofile 14:34:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:34:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:34:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:34:13 INFO - Running setup.py install for mozrunner 14:34:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:34:13 INFO - Running setup.py install for mozscreenshot 14:34:13 INFO - Running setup.py install for moztest 14:34:13 INFO - Running setup.py install for mozversion 14:34:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:34:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:34:14 INFO - Cleaning up... 14:34:14 INFO - Return code: 0 14:34:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:34:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:34:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:34:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:14 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 0x10e4dea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e573618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7f8c84700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e442d30>, '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 0x10e1a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e43ab20>, '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.StQFBnmBcF/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.dfuHpyTCH8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:34:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:34:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:34:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StQFBnmBcF/Render', 14:34:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:14 INFO - 'HOME': '/Users/cltbld', 14:34:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:14 INFO - 'LOGNAME': 'cltbld', 14:34:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:14 INFO - 'MOZ_NO_REMOTE': '1', 14:34:14 INFO - 'NO_EM_RESTART': '1', 14:34:14 INFO - 'PAGER': '/bin/cat', 14:34:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:14 INFO - 'PWD': '/builds/slave/test', 14:34:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:14 INFO - 'SHELL': '/bin/bash', 14:34:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dfuHpyTCH8/Listeners', 14:34:14 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 14:34:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:14 INFO - 'USER': 'cltbld', 14:34:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:14 INFO - 'XPC_FLAGS': '0x0', 14:34:14 INFO - 'XPC_SERVICE_NAME': '0', 14:34:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:34:14 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-p2lZE7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:34:14 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:34:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:34:14 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-xc5N_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:34:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:34:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:34:14 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-Anhftt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:34:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:34:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:34:14 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-UL6jvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:34:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:34:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:34:14 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-TaReP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:34:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:34:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:34:14 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-A_rhdC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:34:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:34:15 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-9UuOQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:34:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:34:15 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-KvjChl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:34:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:34:15 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-ManTw8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:34:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:34:15 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-Hbajfa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:34:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:34:15 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-whJ_Ef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:34:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:34:15 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-YlfZtK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:34:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:34:15 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-QKGz7v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:34:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:34:15 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-DD21pV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:34:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:34:15 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-Ml9xHp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:34:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:34:16 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-hrs5XR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:34:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:34:16 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-qrJgAx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:34:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:34:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:18 INFO - Downloading blessings-1.6.tar.gz 14:34:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:34:18 INFO - Installing collected packages: blessings 14:34:18 INFO - Running setup.py install for blessings 14:34:18 INFO - Successfully installed blessings 14:34:18 INFO - Cleaning up... 14:34:18 INFO - Return code: 0 14:34:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:34:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:34:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:34:19 INFO - Reading from file tmpfile_stdout 14:34:19 INFO - Current package versions: 14:34:19 INFO - blessings == 1.6 14:34:19 INFO - blobuploader == 1.2.4 14:34:19 INFO - docopt == 0.6.1 14:34:19 INFO - functools32 == 3.2.3-2 14:34:19 INFO - jsonschema == 2.5.1 14:34:19 INFO - manifestparser == 1.1 14:34:19 INFO - mozInstall == 1.12 14:34:19 INFO - mozcrash == 0.17 14:34:19 INFO - mozdebug == 0.1 14:34:19 INFO - mozdevice == 0.48 14:34:19 INFO - mozfile == 1.2 14:34:19 INFO - mozhttpd == 0.7 14:34:19 INFO - mozinfo == 0.9 14:34:19 INFO - mozleak == 0.1 14:34:19 INFO - mozlog == 3.2 14:34:19 INFO - moznetwork == 0.27 14:34:19 INFO - mozprocess == 0.22 14:34:19 INFO - mozprofile == 0.28 14:34:19 INFO - mozrunner == 6.11 14:34:19 INFO - mozscreenshot == 0.1 14:34:19 INFO - mozsystemmonitor == 0.3 14:34:19 INFO - moztest == 0.7 14:34:19 INFO - mozversion == 1.4 14:34:19 INFO - psutil == 3.1.1 14:34:19 INFO - requests == 1.2.3 14:34:19 INFO - wsgiref == 0.1.2 14:34:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:34:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:34:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:34:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:19 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 0x10e4dea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e573618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7f8c84700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e442d30>, '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 0x10e1a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e43ab20>, '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.StQFBnmBcF/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.dfuHpyTCH8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:34:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:34:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:34:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StQFBnmBcF/Render', 14:34:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:19 INFO - 'HOME': '/Users/cltbld', 14:34:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:19 INFO - 'LOGNAME': 'cltbld', 14:34:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:19 INFO - 'MOZ_NO_REMOTE': '1', 14:34:19 INFO - 'NO_EM_RESTART': '1', 14:34:19 INFO - 'PAGER': '/bin/cat', 14:34:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:19 INFO - 'PWD': '/builds/slave/test', 14:34:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:19 INFO - 'SHELL': '/bin/bash', 14:34:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dfuHpyTCH8/Listeners', 14:34:19 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 14:34:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:19 INFO - 'USER': 'cltbld', 14:34:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:19 INFO - 'XPC_FLAGS': '0x0', 14:34:19 INFO - 'XPC_SERVICE_NAME': '0', 14:34:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:34:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:34:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:34:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:34:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:34:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:34:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:34:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:34:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:34:19 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:34:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:34:19 INFO - Cleaning up... 14:34:19 INFO - Return code: 0 14:34:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:34:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:34:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:34:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:19 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 0x10e4dea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e573618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7f8c84700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e442d30>, '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 0x10e1a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e43ab20>, '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.StQFBnmBcF/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.dfuHpyTCH8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:34:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:34:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:34:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StQFBnmBcF/Render', 14:34:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:19 INFO - 'HOME': '/Users/cltbld', 14:34:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:19 INFO - 'LOGNAME': 'cltbld', 14:34:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:19 INFO - 'MOZ_NO_REMOTE': '1', 14:34:19 INFO - 'NO_EM_RESTART': '1', 14:34:19 INFO - 'PAGER': '/bin/cat', 14:34:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:19 INFO - 'PWD': '/builds/slave/test', 14:34:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:19 INFO - 'SHELL': '/bin/bash', 14:34:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dfuHpyTCH8/Listeners', 14:34:19 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 14:34:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:19 INFO - 'USER': 'cltbld', 14:34:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:19 INFO - 'XPC_FLAGS': '0x0', 14:34:19 INFO - 'XPC_SERVICE_NAME': '0', 14:34:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:34:19 INFO - Cleaning up... 14:34:19 INFO - Return code: 0 14:34:19 INFO - Running post-action listener: _resource_record_post_action 14:34:19 INFO - Running post-action listener: _start_resource_monitoring 14:34:19 INFO - Starting resource monitoring. 14:34:19 INFO - ##### 14:34:19 INFO - ##### Finished create-virtualenv step (success) 14:34:19 INFO - ##### 14:34:19 INFO - ##### 14:34:19 INFO - ##### Running install step. 14:34:19 INFO - ##### 14:34:19 INFO - Running pre-action listener: _resource_record_pre_action 14:34:19 INFO - Running main action method: install 14:34:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:34:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:34:20 INFO - Reading from file tmpfile_stdout 14:34:20 INFO - Detecting whether we're running mozinstall >=1.0... 14:34:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:34:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:34:20 INFO - Reading from file tmpfile_stdout 14:34:20 INFO - Output received: 14:34:20 INFO - Usage: mozinstall [options] installer 14:34:20 INFO - Options: 14:34:20 INFO - -h, --help show this help message and exit 14:34:20 INFO - -d DEST, --destination=DEST 14:34:20 INFO - Directory to install application into. [default: 14:34:20 INFO - "/builds/slave/test"] 14:34:20 INFO - --app=APP Application being installed. [default: firefox] 14:34:20 INFO - mkdir: /builds/slave/test/build/application 14:34:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:34:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:34:55 INFO - Reading from file tmpfile_stdout 14:34:55 INFO - Output received: 14:34:55 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:34:55 INFO - Running post-action listener: _resource_record_post_action 14:34:55 INFO - ##### 14:34:55 INFO - ##### Finished install step (success) 14:34:55 INFO - ##### 14:34:55 INFO - ##### 14:34:55 INFO - ##### Running run-tests step. 14:34:55 INFO - ##### 14:34:55 INFO - Running pre-action listener: _resource_record_pre_action 14:34:55 INFO - Running main action method: run_tests 14:34:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:34:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:34:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:34:55 INFO - 2016-06-28 14:34:55.186 screenresolution[1935:11724] starting screenresolution argv=screenresolution get 14:34:55 INFO - 2016-06-28 14:34:55.189 screenresolution[1935:11724] Display 0: 1600x1200x32@60 14:34:55 INFO - 2016-06-28 14:34:55.195 screenresolution[1936:11726] starting screenresolution argv=screenresolution list 14:34:55 INFO - Available Modes on Display 0 14:34:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:34:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:34:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:34:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:34:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:34:55 INFO - Intel Iris: 14:34:55 INFO - Chipset Model: Intel Iris 14:34:55 INFO - Type: GPU 14:34:55 INFO - Bus: Built-In 14:34:55 INFO - VRAM (Dynamic, Max): 1536 MB 14:34:55 INFO - Vendor: Intel (0x8086) 14:34:55 INFO - Device ID: 0x0a2e 14:34:55 INFO - Revision ID: 0x0009 14:34:55 INFO - Displays: 14:34:55 INFO - Display: 14:34:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:34:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:34:55 INFO - Display Serial Number: 1600x1200 60 14:34:55 INFO - Main Display: Yes 14:34:55 INFO - Mirror: Off 14:34:55 INFO - Online: Yes 14:34:55 INFO - Rotation: Supported 14:34:55 INFO - Return code: 0 14:34:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:34:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:34:55 INFO - Python 2.7.3 14:34:55 INFO - Return code: 0 14:34:55 INFO - grabbing minidump binary from tooltool 14:34:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:34:55 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 0x10e442d30>, '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 0x10e1a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e43ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:34:55 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:34:55 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 14:34:55 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:34:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:34:55 INFO - Return code: 0 14:34:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:34:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:34:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:34:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:34:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:34:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0016', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:34:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0016 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:34:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StQFBnmBcF/Render', 14:34:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:55 INFO - 'HOME': '/Users/cltbld', 14:34:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:55 INFO - 'LOGNAME': 'cltbld', 14:34:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:34:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:34:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:55 INFO - 'MOZ_NO_REMOTE': '1', 14:34:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:34:55 INFO - 'NO_EM_RESTART': '1', 14:34:55 INFO - 'PAGER': '/bin/cat', 14:34:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:55 INFO - 'PWD': '/builds/slave/test', 14:34:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:34:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:55 INFO - 'SHELL': '/bin/bash', 14:34:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dfuHpyTCH8/Listeners', 14:34:55 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 14:34:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:55 INFO - 'USER': 'cltbld', 14:34:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:55 INFO - 'XPC_FLAGS': '0x0', 14:34:55 INFO - 'XPC_SERVICE_NAME': '0', 14:34:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0016', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:34:55 INFO - mozversion application_buildid: 20160628120923 14:34:55 INFO - mozversion application_changeset: a5f7ad06f03b9ae9ea2ab8e06f89ef67e413ede9 14:34:55 INFO - mozversion application_display_name: Nightly 14:34:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:34:55 INFO - mozversion application_name: Firefox 14:34:55 INFO - mozversion application_remotingname: firefox 14:34:55 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 14:34:55 INFO - mozversion application_vendor: Mozilla 14:34:55 INFO - mozversion application_version: 50.0a1 14:34:55 INFO - mozversion platform_buildid: 20160628120923 14:34:55 INFO - mozversion platform_changeset: a5f7ad06f03b9ae9ea2ab8e06f89ef67e413ede9 14:34:55 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 14:34:55 INFO - mozversion platform_version: 50.0a1 14:34:55 INFO - using testdate: 1467149695 14:34:55 INFO - actual date: 1467149695 14:34:55 INFO - starting webserver on 'localhost:50339' 14:34:55 INFO - SUITE-START | Running 2 tests 14:34:55 INFO - TEST-START | dromaeo_css 14:34:55 INFO - operating with platform_type : mac_ 14:34:55 INFO - Initialising browser for dromaeo_css test... 14:34:55 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmpvct1hc/profile http://localhost:50339/getInfo.html) 14:34:57 INFO - PROCESS | 1946 | __metrics Screen width/height:1600/1200 14:34:57 INFO - PROCESS | 1946 | colorDepth:24 14:34:57 INFO - PROCESS | 1946 | Browser inner width/height: 1024/689 14:34:57 INFO - PROCESS | 1946 | __metrics 14:34:58 INFO - PROCESS | 1946 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:34:58 INFO - PROCESS | 1946 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:34:58 INFO - TEST-INFO | 1946: exit 0 14:34:58 INFO - Browser initialized. 14:34:58 INFO - Running cycle 1/1 for dromaeo_css test... 14:34:58 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmpvct1hc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:35:10 INFO - PROCESS | 1948 | RSS: Main: 243175424 14:35:10 INFO - PROCESS | 1948 | 14:36:02 INFO - PROCESS | 1948 | 1467149762348 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 14:38:13 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:50339/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50339/tests/dromaeo/cssquery-ext.html) 14:38:14 INFO - PROCESS | 1948 | RSS: Main: 378535936 14:38:14 INFO - PROCESS | 1948 | 14:41:17 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:50339/tests/dromaeo/cssquery-ext.html (next: http://localhost:50339/tests/dromaeo/cssquery-jquery.html) 14:41:18 INFO - PROCESS | 1948 | RSS: Main: 382697472 14:41:18 INFO - PROCESS | 1948 | 14:44:20 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:50339/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50339/tests/dromaeo/cssquery-mootools.html) 14:44:21 INFO - PROCESS | 1948 | RSS: Main: 373293056 14:44:21 INFO - PROCESS | 1948 | 14:47:23 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:50339/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50339/tests/dromaeo/cssquery-prototype.html) 14:47:24 INFO - PROCESS | 1948 | RSS: Main: 373825536 14:47:24 INFO - PROCESS | 1948 | 14:50:27 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:50339/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50339/tests/dromaeo/cssquery-yui.html) 14:50:28 INFO - PROCESS | 1948 | RSS: Main: 350511104 14:50:28 INFO - PROCESS | 1948 | 14:53:31 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:50339/tests/dromaeo/cssquery-yui.html (next: http://localhost:50339/tests/dromaeo/cssquery-dojo.html) 14:53:31 INFO - PROCESS | 1948 | RSS: Main: 382328832 14:53:31 INFO - PROCESS | 1948 | 14:53:31 INFO - PROCESS | 1948 | __start_tp_report 14:53:31 INFO - PROCESS | 1948 | _x_x_mozilla_page_load 14:53:31 INFO - PROCESS | 1948 | _x_x_mozilla_page_load_details 14:53:31 INFO - PROCESS | 1948 | |i|pagename|runs| 14:53:31 INFO - PROCESS | 1948 | |0;dojo.html;3361.865525378985;3366.4613661814114;3376.0040787967546;3398.762086653935;3428.657134286572;5640.858978525529;5732.770689172428;5734.856628584283;5760.279965004374;5800.738966746501;5666.405027472118;5686.857828554288;5824.475797178253;5825.854353641151;5872.765089396419;5175.094358487268;5199.66202196856;5246.7901283948595;5259.289995850569;5395.865103372408;4450.443694538183;5346.599005074616;5370.597205209606;5396.730163491829;5413.133898576229;5175.120229560984;5234.110201265756;5250.684958902478;5270.499302566269;5288.10102282609;5203.583713302926;5229.111051121318;5274.103402421598;5275.76259068339;5294.973525132388;5203.167493201107;5205.453427390107;5206.713630750307;5243.10867152585;5301.257823904656;5758.683272420015;5786.016377215883;5809.564282678773;5844.824655260349;5868.647881127104;6116.640127388518;6234.87530249397;6321.273053598803;6327.5570710050315;6346.524010699216;6300.149479820211;6308.684565771693;6381.968090159566;6399.712012959428;6402.93597064028;5747.1379293106365;5902.852428689274;5945.940540594539;5958.165856780054;5958.940410595926;6250.218722659668;6304.306526282106;6312;6347.174867267226;6357.650329231844;6194.5044396448175;6234.251889773165;6249.75000999964;6255.218097737783;6278.46633036197;6129.542179248642;6200.286966998763;6290.811275661737;6320.462760665395;6327;6241.78153764616;6318;6338.080978258219;6338.397852204033;6350.174477317919;6295.118683384329;6356.45970092538;6366.204224471941;6370.331115232926;6388.009858471944;4122.9381559276635;4946.183879659888;4997.425296090923;5005.524475174852;5041.0422019816115;4422.314541246112;4429.49060857999;4460.531644177379;4467.30756732707;4491.977540112279;5790.305163380422;5818.069108942549;5825.79609713658;5827.067669172912;5881.676507792027;6119.776756598045;6249.437550620466;6263.185785847811;6339.587926784744;6380.585261957958;5793.2179155814265;5794.478496935296;5812.331581868055;5812.505936995402;5827.446392592697;5453.7818487260065;5454.645448045864;5456.154296084113;5462.562994960394;5478.369987451414;6283.748650054038;6304.18045654062;6364.777232796922;6365.649889256136;6415.973444248898;6299.590526615755;6364.745410183726;6368.42684158428;6371.649559274284;6371.96814015927;6400.647964362005;6406.167198264196;6417.6470294132405;6430.871382572229;6436.324185960594;6110.388961103854;6133.73253493014;6146.754129834757;6225.035119556476;6246.281677607133;6346.333634968447;6347.936520634735;6356;6357.237131544244;6369.01280301554;6192.659403732838;6214.4407003369915;6216.595921265103;6218.968905155445;6222.471089957405;6010.3088144863905;6085.786997455068;6101.913673707429;6123.755049797958;6134.069235337226;5878.500327472042;6053.334133245251;6054.182685337423;6055.182550355822;6062.144193638225;6183.84540386476;6214.9067763984485;6217.347178546368;6230.7819226326865;6236.438720515175;5858.502027327725;5902.2622172228475;5948.762049518144;6017.608855424383;6036.939630603813 14:53:31 INFO - PROCESS | 1948 | |1;ext.html;485.45386440254714;493.9975300123499;512.6795752654591;514.570333771301;544.6541446181675;30498.390032199342;41353.17293654129;42109.78945105274;42144;42232;42290;42584.57415425845;42676.78661606695;42707.572924270746;42822.357664635056;8792.296616270714;9346.299027572924;9509.191718703918;9529.714108576754;9534.427934323963;8796.780080498007;9944.204463642891;12110.455029523648;12118.515259389615;12276.140670153092;11968.581099661513;12038.81941770874;12048.096392770578;12053.578124765634;12082.637520874345;8884.689035883777;11861.46623401949;11991.340476273801;12079.999999999956;12191.963683086948;11734.882651173464;11789.997850182745;11827.526898924032;11830;11839.467223974942;40230;40614;40621.593784062374;40638.56168629892;40694.38958415626;3418.2308980479193;3420.6066302375298;3425.743069269815;3457.429524128516;3535.505029295901;30725.924592639374;31262.21844453884;32604.021879343658;32683;32716.672833271838;2909.3890283040587;3368.2421455172635;3371.089805752458;3419.230673098558;3432.5194472773583;516419;521168;522117;522567;522716;62243;62608;62704.68647656824;62797;62799.68600157061;63441;63654;63822;63902.680486597266;64048;16500.092494912897;16512.25694843735;16710.4151354702;16725.581860453585;16744.58138546522;11763;11965.940170299093;12755.107142500121;12852.100352975389;12940.482380704854;18471.445856624323;18491.722624160648;18551.90724046371;18552.628947420977;18561.44315670532;6821.897671534931;8121.959390203011;8304.501729896225;8419.36854735885;8451.323894088458;916.3951791817502;924.7872989212519;1005.4017859373661;1041.286718597763;1055.6094245129352;26383;28560.85719571389;30175.698243017727;30876.845615771777;30955.0713478596;1041.6250149946013;1093.3439936038314;1095.1512577752305;1160.640201537526;1168.4391492083848;13791.379387927367;15630.3747850087;15657.29542170605;15728;15746.291416886268;724421;726476;730030;730061;731057;58015;59744.22046236291;59815.102773458435;59842.40157598456;59973.70013149906;1258.993705031469;1296.2870421268265;1391.2765362011899;1395.958121256364;1418.7375335562954;30063.849680751457;31063.68936310608;31489.60291178116;31528.684713153492;31827.522587161675;48499.51500485091;49366.75316623537;49425.75287123541;49531.50468495269;49543;458959;494049;495462;495526;497413;22978.540429190987;25640.61539076878;25869.09458168955;25947;26476.86761566257;47280;47877;48396;48464.7576762128;48839.75580122077;34480.62077516871;38766.812695041655;39666.40500392439;40083.99790005273;40272.1945561093;37877.81061094677;38342.23315533729;38668.80665596654;39217.80391098026;39451.21097577975;37712.24575508529;38156.2368752629;38489.42265865958;38506.80746596361;38612.80693596626;33974.49038264379;34074;34909.65090349064;34922.47616285809;35176.296474070885;23766.881165594063;25222.62166067547;25431.982720690965;25598.872005639852;26259.949602015706 14:53:31 INFO - PROCESS | 1948 | |2;jquery.html;386.78533413955245;387.891390410685;393.04882185112007;393.83783031158913;413.6835320979449;20681.896590517026;21148.154073837046;21677.916104194806;21856.344309670694;21879.99999999998;19942.70085948712;20316.67941583796;21053.894730526365;21084.156633734634;22447.663285050698;5923.970380148104;6188.257409110913;6200.937990620081;6243.282022567395;6247.937520624804;6035.245594300713;6083.148359229712;6110.511159107261;6222.346653601375;6233.127362169277;6153.969230153843;6173.506119510428;6188.907166392508;6201.534884883629;6213.440790328869;5824.621399609033;6084.209052823105;6087.847803804874;6096.603720758137;6186.505079593644;5268.525832675039;5274.92087618686;5283.286756287929;5284.947150528523;5290.153575427913;22183.66724499134;27080.32299192516;27564.724352756617;27652.58521122185;27670.031548895895;2272.4659704969317;2333.241696448644;2344.284993077111;2360.386299562109;3003.5044217704053;15774.921125394414;15963.920180399024;16083.839161608352;16233.350665973347;16763.66472670552;1878.3998889224981;1894.4506093232947;1915.4636701723539;1951.0439884456514;1968.0651690447066;74813.62593186999;84535.577322113;123162.76837231692;124590;125435.3728231353;10947.124230061736;11246.550137994389;11440.885591143982;11449.71375715597;11599.130065244972;14160.433582656784;14234.572962810926;14293.78559321611;14298.785518217235;14336.354864031144;8607.784805379915;8773.561321933877;8792.780180495538;8867.334949878785;8905.243054340453;7658.617069146632;8482.727590861421;8732.869006964773;8752.387332886765;8869.68956086534;5843.3864444233595;6813.420859226922;6910.412614927787;6914.51598388108;6930.896036559456;1706.743673756505;1711.8544923681377;1855.3691744806831;1924.8363889069585;1939.21461807968;908.3868388837508;934.8504239321676;991.9435800872049;1013.5236438873718;1033.390299723167;22387.552248955257;23061.53876922452;23141.652875206884;23259.534809303717;23293.650595241084;904.6291020681489;984.8965858584888;1009.2632378363835;1021.7547788530848;1076.9192310576739;10839;11994.940025299993;12083.637490875288;12118.939405302917;12271.570495032809;537961;592104;600103.9994799998;600817;609917;79612;115933;122132;124628;127826;960.7790208252142;982.6953644370268;991.5290237137447;1012.5038731021747;1012.5443550402348;19875.801241987974;20500.589988200452;20519.58960820805;20550.486237844172;20555.4861128473;49906.500934991636;51658.74170629123;51927.48072519226;52490.68773280698;52728.762414821016;72679.90980135408;72743.90884136848;72934.82567353365;73076.63461682657;73284.90072648808;6908.412784913137;7341.6696248670005;7467.806444203731;7647.349975252166;8181.836363272821;11757.529698812294;13440.865591344353;13504.797428038784;13656;13815.032947693566;11066.502007409847;11147.498362573866;11704.297742135499;11800.586979455678;11980.520779168735;11633.6509904703;11661.708457288636;11668;11675.3578553177;11862.58480953197;11328;11851.288922664668;11858.881411185777;12095.455704493495;12132.150749447454;8048.301736775505;8417.663293468193;8456.83086338282;8494.32045436376;8565.357598180286;8478.788030299045;10435.634752783617;11647.068234541048;11677.76644467089;11984.28094314344 14:53:31 INFO - PROCESS | 1948 | |3;mootools.html;1385.528920167143;1420.4886240953256;1556.5719427157535;1774.8402643762058;1971.5366888781134;7284.890726639098;7966.641501132449;9001.324900632462;9014.819703605925;9086.909130908689;8661.263792577638;8873.378863479558;8886.333524985635;8893.466392016484;8894.77763055924;2098.7271654684946;2180.6292930201826;2182.4980254541465;2372.3238876920036;2408.385861605288;2143.65701487762;2205.151016858513;2337.719473663154;2435.585950388438;2436.549238390903;2366.8106551475926;2396;2399.4961058177805;2400.2799160251943;2406.626972819216;1969.6257711034857;2078.511549785799;2364.609839376501;2366.8106551475926;2366.857988520677;2318.4435735423544;2399.8560086394873;2404;2414.2877851033813;2414.6015907375263;9014.459132452073;9087.136722011399;9095;9097.408668436598;9100.135487128713;5250.553702935217;5428.809991650273;5432.076546987022;5435.130379139358;5447.645903016291;3478.3565040467342;3584.301061293049;3609.873654422083;3656.7988760618155;3659.743817932746;1045.2265323660513;1065.5737704918017;1069.6256310291335;1087.1085709718034;1094.8467214589964;12009.519619215222;12033.63899083029;12044;12051;12054.638360849101;4708.623310135181;4756.9762151189025;4851.490593487703;4974.552290293891;4983.90032199354;9323.207527360115;9359.29805264608;9460.668876589287;9462.52687365629;9463.574139163755;5331.040412725747;5331.200319952039;5333.359996800331;5334.786608535693;5339.14573668219;4801.855944321676;4987.526185012418;4997.350344455198;5000.799968001312;5007.349044624249;3656.3601369760186;3700.3339398908456;3764.8682296119505;3785.4511095890944;3806.314863324573;1129.4239937631703;1139.316410153901;1142.0520967596874;1151.1193936638479;1151.159653452984;823.2097186700714;897.4077109107957;916.3539704508439;939.3987847777428;954.1365064616533;5979.730912108966;6058.1215723719815;6115.388461153938;6224.533160013018;6312.084747711556;936.3539157980971;954.7613096725819;983.095552092077;983.6803039012207;983.8868530118984;3672.247189326182;3733.9731573817417;3836.1368692044625;4003.599640035973;4042.130941847517;10199.28604997658;10483.318584291997;10891.509882055327;10908.781824363468;10911.07255883243;10586;10702.411367374869;10810.243282970274;10840.295380800222;10848.620298289521;7865.921340786634;8141.755747327589;8307.626156822958;8328.250457458858;8378.539180345018;6007.158997740233;6128.264608247039;6130.141780150693;6229.221347331583;6249.96875015622;9677.903220967983;10204.13264872494;10218.437985910548;10328.793424131325;10345.017223363768;9258;9280.118388753057;9673.854892176481;10393.012663796928;10397.064264216257;6988.650567471708;7173.569585824867;7179.246179151114;7199.568025918462;7233.891491627735;7111.146662400545;8400.28597569189;8494.277986371228;8507.957460212907;8519.574021299035;7619.65711542993;7682.193369696101;7686.231376862269;7801.1808760079375;7817.296443320129;7423.072115985502;7503.324700776738;7575.242475752601;7602.163761986067;7721.227877212234;7055.082839230867;7064.293570642894;7106.395956343768;7107.75122870697;7151.070360853056;4489.865304040884;4954.5788607968725;4976.527229913152;5055.418626857959;5106.744662766772;4441.755703436342;5038.899222015612;5084.669496482717;5103.770330335218;5179.1195496766395 14:53:31 INFO - PROCESS | 1948 | |4;prototype.html;308.21097989147785;312.51560081873106;316.8257458397882;322.21219119253436;323.88178314915035;4457.754823484707;5104.438511763703;5374.946250537499;5388.137897936331;5394.244805727191;5073.1375666136755;5346.786128554862;5365;5386.656804290136;5392.649477783942;3272.296456261903;3408.113890388492;3659.5425571803526;3866.5360156781226;4022.153305267146;3275.525048867925;3548.982255088734;4076.286649836267;4089.243489954353;4109.56849530798;3277.3936821687944;4030.415589739486;4040.616141466556;4065;4145.751254924699;3851.28751181031;3976.5426975897562;3981.323175060232;4095.7747323897174;4263.445752052245;3533.2933413317182;3538.840752166133;3549.402768754071;3573.910652233715;3578.6063533011347;4265.936010959838;4689.8358557450665;5064.670796398223;5084.872878178038;5200.453952334989;4109.56849530798;4174.666026717855;4420.359047938063;4424.292113261863;4485.596296333313;4771.928421073687;4944.283078953567;5602.467765562265;5631.549476041907;5661.971690141564;3936.0356712605594;3944.7238693291483;3953.6639385652256;3956.742811717258;3987.760734355948;5425.6202065855805;5571.526420254283;5896.764129434818;5921.911171332434;5951.137085122677;3413.4026545354463;3435.209901722618;3958.2281455115976;3986.126520535344;4018.0758425562285;4054.71616986814;4063.349864021742;4063.8577649782706;4087.46862907826;4119.711620186558;3679.68722658577;4175.519815221228;4297.505786834554;4704.223803072523;4704.764761761899;5000.574951129195;5604.971975140098;5625;5696.11710184922;5755.366909639936;1204.8914399800349;1251.5431867368527;1329.335332333833;1367.6375760423496;1403.4316101978716;790.3440144679902;813.8819871118654;848.499385362639;878.4685265414332;887.5384799904143;4492.752898590544;4834.854954351375;4907.993861258434;5252.527272545412;5264.42091369949;5753.8066373162455;5784.161296612052;5795.333536643256;5805.564582656318;5808.6224395414165;4684.297355396718;5009.699418034929;5232.869178270574;5265.973670131625;5289.70906600133;2698.9595156072673;2766.806323557372;2832.895170883357;2995.9101226963226;3038.68093850147;5171.276021357013;5317.73411329432;5344.786208551692;5827;6026.306974697878;4619.729574367008;5316.368397643191;5661.688607126565;5974.522038236931;6007.399260074045;5642.943570564406;5647.209390685225;5810.331811841608;6069.722349931076;6136.9079463808075;5090.821821236239;5328.653637513549;5867.970660146672;5879.941610509978;5904.557158213065;4910.410750709795;5833.416658334133;5836.708164591839;5880.7065244553805;5915.290165180206;5179.689218646893;6093.116498107661;6122.540809439399;6141.877162456815;6192.164057852132;3773.7924414157483;3856.36369998947;3869.342211824053;3888.805559721946;3912.237113762789;5829.3296270927685;5863.824085277449;5874.823755287348;5888.174665853599;5901.527877769682;4702.741349225688;5285.365756109291;5451.836444906659;5493.945060549503;5727.140928860705;4828.951710482991;5507.5593952484505;5530.695811730394;5554.222408862682;5676.318841739017;4927.90564010287;5137.9486205138965;5518.972405137949;5533.391326953952;5586.553075753849;4597.908041839211;4951.5048495150195;5399.676019438846;5426.751847075116;5570.359408667893;5339.786408543615;5542.944570554243;5554.722413844757;5567.4432556744005;5569.721513924369 14:53:31 INFO - PROCESS | 1948 | |5;yui.html;707.6214225389415;711.9038929744485;722.6206241723091;779.7621725373758;851.4210336970857;15322.54032379029;15901.761473577906;15949.601259968507;15953.601159971007;16068.116253606047;13007.869921300784;13080.73838523229;13085.803712944313;13106.80339794904;13110.803337949937;711.8540699156661;738.8079099434159;738.8263758016861;895.1809094678378;908.9091090890925;733.3033618062861;739.6708464733196;884.4737381258174;913.0458670689146;916.839553078212;961.4567769210408;969.0067680627343;972.479723348009;973.4159504297436;975.6438899801557;970.854371844222;971.03382134776;973.8003709239589;974.0016483104874;975.024975024968;558.2212813125667;695.09984569982;714.8713231618307;715.309726114297;723.9095113110861;15491.225438728019;15513.844861551466;15548.533543993699;15554.377824886991;15563.37746490139;12102.653690202593;14034.719305613933;14351.928240358835;14491.927540362336;14748.262586870613;12628.242305461701;12827.807582886264;13087;13502.594922152352;13607.863921360758;4575.130725162208;4598.218302888484;4608.516105808875;4761.309610106549;4785.162596545625;113713;115555;118001;118370.40814795958;118602.99999999914;11384.316940983568;11391.829122563167;11420.494981013977;11504.367259800623;11536.365499897585;23103.960321785224;23364.948577314062;23372;23507.529849402916;23617;8105.432619716681;8143.75568732939;8180.672773089129;8187.38594605407;8223.917760822435;3658.213484100878;4266.530681625019;4367.039251364695;4439.42287502629;4512.616427603625;3527.5414196154848;3603.531540899666;3644.03433090226;3646.744727869024;3652.5069115669576;642.1010585180784;645.257953353647;645.8449972006779;659.818924125819;668.749219042859;550.5430492691057;599.6312267955263;613.365167052099;639.6290151711996;645.1902861908275;9111.954440227757;9159.221466175317;9207.631694732136;9279.304052196112;9378.202852757455;571.7541457173455;581.8080033588906;610.697704636208;634.8698516804044;647.3494138390887;4040.313146765057;4404.361367601679;4648.604868586208;4743.549362810528;4799.400074990626;139752;147915.52084479018;154313;155009;158781;121329;122142.38928805299;122890;123928;124857;110455;112771.43614281875;114445.42777286227;114455;117517;9024;9173.678921237724;9182.357234993486;9208.585613647536;9449.763755906157;60966;61484.385156147866;62242.68878655759;62794;63452.365476346495;94128;95797;96726;97412.51293743486;99090;11720.355380454157;11833.881661183277;12040.578579749666;12526.574161202298;12606.810897836356;11360.431978401211;13644.522441714493;13680.794788077988;13719.342804984744;14245.786313205102;12286.508539658313;12532.937335313265;12706.36468176606;12745.235285882876;13087.41106650222;10820.458977051274;12503.312317822607;12535.93732031334;12763.425645846144;13225.669358266121;10646.414447205478;12105.757884842078;12217.389130543615;12319.87680123223;12786.744265114832;10405.58377664885;10517.286093322875;10580.629677961235;10982.780344393228;11040.392778397265;10566.682999510027;10589;10660.25378223517;10806.783864322511;10825.188110891557 14:53:31 INFO - PROCESS | 1948 | __end_tp_report 14:53:31 INFO - PROCESS | 1948 | __start_cc_report 14:53:31 INFO - PROCESS | 1948 | _x_x_mozilla_cycle_collect,1310 14:53:31 INFO - PROCESS | 1948 | __end_cc_report 14:53:31 INFO - PROCESS | 1948 | __startTimestamp1467150811410__endTimestamp 14:53:31 INFO - PROCESS | 1948 | 14:53:31 INFO - PROCESS | 1948 | ------- Summary: start ------- 14:53:31 INFO - PROCESS | 1948 | Number of tests: 6 14:53:31 INFO - PROCESS | 1948 | 14:53:31 INFO - PROCESS | 1948 | [#0] dojo.html Cycles:165 Average:5808.86 Median:6053.33 stddev:666.80 (11.0%) stddev-sans-first:640.62 14:53:31 INFO - PROCESS | 1948 | Values: 3361.9 3366.5 3376.0 3398.8 3428.7 5640.9 5732.8 5734.9 5760.3 5800.7 5666.4 5686.9 5824.5 5825.9 5872.8 5175.1 5199.7 5246.8 5259.3 5395.9 4450.4 5346.6 5370.6 5396.7 5413.1 5175.1 5234.1 5250.7 5270.5 5288.1 5203.6 5229.1 5274.1 5275.8 5295.0 5203.2 5205.5 5206.7 5243.1 5301.3 5758.7 5786.0 5809.6 5844.8 5868.6 6116.6 6234.9 6321.3 6327.6 6346.5 6300.1 6308.7 6382.0 6399.7 6402.9 5747.1 5902.9 5945.9 5958.2 5958.9 6250.2 6304.3 6312.0 6347.2 6357.7 6194.5 6234.3 6249.8 6255.2 6278.5 6129.5 6200.3 6290.8 6320.5 6327.0 6241.8 6318.0 6338.1 6338.4 6350.2 6295.1 6356.5 6366.2 6370.3 6388.0 4122.9 4946.2 4997.4 5005.5 5041.0 4422.3 4429.5 4460.5 4467.3 4492.0 5790.3 5818.1 5825.8 5827.1 5881.7 6119.8 6249.4 6263.2 6339.6 6380.6 5793.2 5794.5 5812.3 5812.5 5827.4 5453.8 5454.6 5456.2 5462.6 5478.4 6283.7 6304.2 6364.8 6365.6 6416.0 6299.6 6364.7 6368.4 6371.6 6372.0 6400.6 6406.2 6417.6 6430.9 6436.3 6110.4 6133.7 6146.8 6225.0 6246.3 6346.3 6347.9 6356.0 6357.2 6369.0 6192.7 6214.4 6216.6 6219.0 6222.5 6010.3 6085.8 6101.9 6123.8 6134.1 5878.5 6053.3 6054.2 6055.2 6062.1 6183.8 6214.9 6217.3 6230.8 6236.4 5858.5 5902.3 5948.8 6017.6 6036.9 14:53:31 INFO - PROCESS | 1948 | 14:53:31 INFO - PROCESS | 1948 | [#1] ext.html Cycles:180 Average:71518.28 Median:19355.90 stddev:157618.81 (814.3%) stddev-sans-first:157970.74 14:53:31 INFO - PROCESS | 1948 | Values: 485.5 494.0 512.7 514.6 544.7 30498.4 41353.2 42109.8 42144.0 42232.0 42290.0 42584.6 42676.8 42707.6 42822.4 8792.3 9346.3 9509.2 9529.7 9534.4 8796.8 9944.2 12110.5 12118.5 12276.1 11968.6 12038.8 12048.1 12053.6 12082.6 8884.7 11861.5 11991.3 12080.0 12192.0 11734.9 11790.0 11827.5 11830.0 11839.5 40230.0 40614.0 40621.6 40638.6 40694.4 3418.2 3420.6 3425.7 3457.4 3535.5 30725.9 31262.2 32604.0 32683.0 32716.7 2909.4 3368.2 3371.1 3419.2 3432.5 516419.0 521168.0 522117.0 522567.0 522716.0 62243.0 62608.0 62704.7 62797.0 62799.7 63441.0 63654.0 63822.0 63902.7 64048.0 16500.1 16512.3 16710.4 16725.6 16744.6 11763.0 11965.9 12755.1 12852.1 12940.5 18471.4 18491.7 18551.9 18552.6 18561.4 6821.9 8122.0 8304.5 8419.4 8451.3 916.4 924.8 1005.4 1041.3 1055.6 26383.0 28560.9 30175.7 30876.8 30955.1 1041.6 1093.3 1095.2 1160.6 1168.4 13791.4 15630.4 15657.3 15728.0 15746.3 724421.0 726476.0 730030.0 730061.0 731057.0 58015.0 59744.2 59815.1 59842.4 59973.7 1259.0 1296.3 1391.3 1396.0 1418.7 30063.8 31063.7 31489.6 31528.7 31827.5 48499.5 49366.8 49425.8 49531.5 49543.0 458959.0 494049.0 495462.0 495526.0 497413.0 22978.5 25640.6 25869.1 25947.0 26476.9 47280.0 47877.0 48396.0 48464.8 48839.8 34480.6 38766.8 39666.4 40084.0 40272.2 37877.8 38342.2 38668.8 39217.8 39451.2 37712.2 38156.2 38489.4 38506.8 38612.8 33974.5 34074.0 34909.7 34922.5 35176.3 23766.9 25222.6 25432.0 25598.9 26259.9 14:53:31 INFO - PROCESS | 1948 | 14:53:31 INFO - PROCESS | 1948 | [#2] jquery.html Cycles:180 Average:34381.95 Median:2338.76 stddev:97699.21 (4177.4%) stddev-sans-first:97939.93 14:53:31 INFO - PROCESS | 1948 | Values: 386.8 387.9 393.0 393.8 413.7 20681.9 21148.2 21677.9 21856.3 21880.0 19942.7 20316.7 21053.9 21084.2 22447.7 5924.0 6188.3 6200.9 6243.3 6247.9 6035.2 6083.1 6110.5 6222.3 6233.1 6154.0 6173.5 6188.9 6201.5 6213.4 5824.6 6084.2 6087.8 6096.6 6186.5 5268.5 5274.9 5283.3 5284.9 5290.2 22183.7 27080.3 27564.7 27652.6 27670.0 2272.5 2333.2 2344.3 2360.4 3003.5 15774.9 15963.9 16083.8 16233.4 16763.7 1878.4 1894.5 1915.5 1951.0 1968.1 74813.6 84535.6 123162.8 124590.0 125435.4 10947.1 11246.6 11440.9 11449.7 11599.1 14160.4 14234.6 14293.8 14298.8 14336.4 8607.8 8773.6 8792.8 8867.3 8905.2 7658.6 8482.7 8732.9 8752.4 8869.7 5843.4 6813.4 6910.4 6914.5 6930.9 1706.7 1711.9 1855.4 1924.8 1939.2 908.4 934.9 991.9 1013.5 1033.4 22387.6 23061.5 23141.7 23259.5 23293.7 904.6 984.9 1009.3 1021.8 1076.9 10839.0 11994.9 12083.6 12118.9 12271.6 537961.0 592104.0 600104.0 600817.0 609917.0 79612.0 115933.0 122132.0 124628.0 127826.0 960.8 982.7 991.5 1012.5 1012.5 19875.8 20500.6 20519.6 20550.5 20555.5 49906.5 51658.7 51927.5 52490.7 52728.8 72679.9 72743.9 72934.8 73076.6 73284.9 6908.4 7341.7 7467.8 7647.3 8181.8 11757.5 13440.9 13504.8 13656.0 13815.0 11066.5 11147.5 11704.3 11800.6 11980.5 11633.7 11661.7 11668.0 11675.4 11862.6 11328.0 11851.3 11858.9 12095.5 12132.2 8048.3 8417.7 8456.8 8494.3 8565.4 8478.8 10435.6 11647.1 11677.8 11984.3 14:53:31 INFO - PROCESS | 1948 | 14:53:31 INFO - PROCESS | 1948 | [#3] mootools.html Cycles:180 Average:5733.00 Median:5047.16 stddev:3223.12 (63.9%) stddev-sans-first:3215.60 14:53:31 INFO - PROCESS | 1948 | Values: 1385.5 1420.5 1556.6 1774.8 1971.5 7284.9 7966.6 9001.3 9014.8 9086.9 8661.3 8873.4 8886.3 8893.5 8894.8 2098.7 2180.6 2182.5 2372.3 2408.4 2143.7 2205.2 2337.7 2435.6 2436.5 2366.8 2396.0 2399.5 2400.3 2406.6 1969.6 2078.5 2364.6 2366.8 2366.9 2318.4 2399.9 2404.0 2414.3 2414.6 9014.5 9087.1 9095.0 9097.4 9100.1 5250.6 5428.8 5432.1 5435.1 5447.6 3478.4 3584.3 3609.9 3656.8 3659.7 1045.2 1065.6 1069.6 1087.1 1094.8 12009.5 12033.6 12044.0 12051.0 12054.6 4708.6 4757.0 4851.5 4974.6 4983.9 9323.2 9359.3 9460.7 9462.5 9463.6 5331.0 5331.2 5333.4 5334.8 5339.1 4801.9 4987.5 4997.4 5000.8 5007.3 3656.4 3700.3 3764.9 3785.5 3806.3 1129.4 1139.3 1142.1 1151.1 1151.2 823.2 897.4 916.4 939.4 954.1 5979.7 6058.1 6115.4 6224.5 6312.1 936.4 954.8 983.1 983.7 983.9 3672.2 3734.0 3836.1 4003.6 4042.1 10199.3 10483.3 10891.5 10908.8 10911.1 10586.0 10702.4 10810.2 10840.3 10848.6 7865.9 8141.8 8307.6 8328.3 8378.5 6007.2 6128.3 6130.1 6229.2 6250.0 9677.9 10204.1 10218.4 10328.8 10345.0 9258.0 9280.1 9673.9 10393.0 10397.1 6988.7 7173.6 7179.2 7199.6 7233.9 7111.1 8400.3 8494.3 8508.0 8519.6 7619.7 7682.2 7686.2 7801.2 7817.3 7423.1 7503.3 7575.2 7602.2 7721.2 7055.1 7064.3 7106.4 7107.8 7151.1 4489.9 4954.6 4976.5 5055.4 5106.7 4441.8 5038.9 5084.7 5103.8 5179.1 14:53:31 INFO - PROCESS | 1948 | 14:53:31 INFO - PROCESS | 1948 | [#4] prototype.html Cycles:180 Average:4532.81 Median:5087.85 stddev:1411.84 (27.7%) stddev-sans-first:1379.74 14:53:31 INFO - PROCESS | 1948 | Values: 308.2 312.5 316.8 322.2 323.9 4457.8 5104.4 5374.9 5388.1 5394.2 5073.1 5346.8 5365.0 5386.7 5392.6 3272.3 3408.1 3659.5 3866.5 4022.2 3275.5 3549.0 4076.3 4089.2 4109.6 3277.4 4030.4 4040.6 4065.0 4145.8 3851.3 3976.5 3981.3 4095.8 4263.4 3533.3 3538.8 3549.4 3573.9 3578.6 4265.9 4689.8 5064.7 5084.9 5200.5 4109.6 4174.7 4420.4 4424.3 4485.6 4771.9 4944.3 5602.5 5631.5 5662.0 3936.0 3944.7 3953.7 3956.7 3987.8 5425.6 5571.5 5896.8 5921.9 5951.1 3413.4 3435.2 3958.2 3986.1 4018.1 4054.7 4063.3 4063.9 4087.5 4119.7 3679.7 4175.5 4297.5 4704.2 4704.8 5000.6 5605.0 5625.0 5696.1 5755.4 1204.9 1251.5 1329.3 1367.6 1403.4 790.3 813.9 848.5 878.5 887.5 4492.8 4834.9 4908.0 5252.5 5264.4 5753.8 5784.2 5795.3 5805.6 5808.6 4684.3 5009.7 5232.9 5266.0 5289.7 2699.0 2766.8 2832.9 2995.9 3038.7 5171.3 5317.7 5344.8 5827.0 6026.3 4619.7 5316.4 5661.7 5974.5 6007.4 5642.9 5647.2 5810.3 6069.7 6136.9 5090.8 5328.7 5868.0 5879.9 5904.6 4910.4 5833.4 5836.7 5880.7 5915.3 5179.7 6093.1 6122.5 6141.9 6192.2 3773.8 3856.4 3869.3 3888.8 3912.2 5829.3 5863.8 5874.8 5888.2 5901.5 4702.7 5285.4 5451.8 5493.9 5727.1 4829.0 5507.6 5530.7 5554.2 5676.3 4927.9 5137.9 5519.0 5533.4 5586.6 4597.9 4951.5 5399.7 5426.8 5570.4 5339.8 5542.9 5554.7 5567.4 5569.7 14:53:31 INFO - PROCESS | 1948 | 14:53:31 INFO - PROCESS | 1948 | [#5] yui.html Cycles:180 Average:25128.40 Median:3565.54 stddev:40316.07 (1130.7%) stddev-sans-first:40387.47 14:53:31 INFO - PROCESS | 1948 | Values: 707.6 711.9 722.6 779.8 851.4 15322.5 15901.8 15949.6 15953.6 16068.1 13007.9 13080.7 13085.8 13106.8 13110.8 711.9 738.8 738.8 895.2 908.9 733.3 739.7 884.5 913.0 916.8 961.5 969.0 972.5 973.4 975.6 970.9 971.0 973.8 974.0 975.0 558.2 695.1 714.9 715.3 723.9 15491.2 15513.8 15548.5 15554.4 15563.4 12102.7 14034.7 14351.9 14491.9 14748.3 12628.2 12827.8 13087.0 13502.6 13607.9 4575.1 4598.2 4608.5 4761.3 4785.2 113713.0 115555.0 118001.0 118370.4 118603.0 11384.3 11391.8 11420.5 11504.4 11536.4 23104.0 23364.9 23372.0 23507.5 23617.0 8105.4 8143.8 8180.7 8187.4 8223.9 3658.2 4266.5 4367.0 4439.4 4512.6 3527.5 3603.5 3644.0 3646.7 3652.5 642.1 645.3 645.8 659.8 668.7 550.5 599.6 613.4 639.6 645.2 9112.0 9159.2 9207.6 9279.3 9378.2 571.8 581.8 610.7 634.9 647.3 4040.3 4404.4 4648.6 4743.5 4799.4 139752.0 147915.5 154313.0 155009.0 158781.0 121329.0 122142.4 122890.0 123928.0 124857.0 110455.0 112771.4 114445.4 114455.0 117517.0 9024.0 9173.7 9182.4 9208.6 9449.8 60966.0 61484.4 62242.7 62794.0 63452.4 94128.0 95797.0 96726.0 97412.5 99090.0 11720.4 11833.9 12040.6 12526.6 12606.8 11360.4 13644.5 13680.8 13719.3 14245.8 12286.5 12532.9 12706.4 12745.2 13087.4 10820.5 12503.3 12535.9 12763.4 13225.7 10646.4 12105.8 12217.4 12319.9 12786.7 10405.6 10517.3 10580.6 10982.8 11040.4 10566.7 10589.0 10660.3 10806.8 10825.2 14:53:31 INFO - PROCESS | 1948 | -------- Summary: end -------- 14:53:31 INFO - PROCESS | 1948 | 14:53:31 INFO - TEST-INFO | 1948: exit 0 14:53:32 INFO - TEST-OK | dromaeo_css | took 1116225ms 14:53:32 INFO - TEST-START | kraken 14:53:32 INFO - operating with platform_type : mac_ 14:53:32 INFO - Initialising browser for kraken test... 14:53:32 INFO - TEST-INFO | started process 1952 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmpRatHO4/profile http://localhost:50339/getInfo.html) 14:53:34 INFO - PROCESS | 1952 | __metrics Screen width/height:1600/1200 14:53:34 INFO - PROCESS | 1952 | colorDepth:24 14:53:34 INFO - PROCESS | 1952 | Browser inner width/height: 1024/689 14:53:34 INFO - PROCESS | 1952 | __metrics 14:53:34 INFO - PROCESS | 1952 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:53:34 INFO - PROCESS | 1952 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:53:34 INFO - TEST-INFO | 1952: exit 0 14:53:34 INFO - Browser initialized. 14:53:34 INFO - Running cycle 1/1 for kraken test... 14:53:34 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmpRatHO4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:53:46 INFO - PROCESS | 1953 | RSS: Main: 243331072 14:53:46 INFO - PROCESS | 1953 | 14:53:49 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:50339/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50339/tests/kraken/driver.html?testName=audio-beat-detection) 14:53:50 INFO - PROCESS | 1953 | RSS: Main: 294072320 14:53:50 INFO - PROCESS | 1953 | 14:53:53 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:50339/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50339/tests/kraken/driver.html?testName=audio-dft) 14:53:53 INFO - PROCESS | 1953 | RSS: Main: 303706112 14:53:53 INFO - PROCESS | 1953 | 14:53:57 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:50339/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50339/tests/kraken/driver.html?testName=audio-fft) 14:53:58 INFO - PROCESS | 1953 | RSS: Main: 385982464 14:53:58 INFO - PROCESS | 1953 | 14:53:59 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:50339/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50339/tests/kraken/driver.html?testName=audio-oscillator) 14:54:00 INFO - PROCESS | 1953 | RSS: Main: 288047104 14:54:00 INFO - PROCESS | 1953 | 14:54:02 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:50339/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50339/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:54:03 INFO - PROCESS | 1953 | RSS: Main: 292081664 14:54:03 INFO - PROCESS | 1953 | 14:54:05 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:50339/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50339/tests/kraken/driver.html?testName=imaging-darkroom) 14:54:06 INFO - PROCESS | 1953 | RSS: Main: 329498624 14:54:06 INFO - PROCESS | 1953 | 14:54:09 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:50339/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50339/tests/kraken/driver.html?testName=imaging-desaturate) 14:54:10 INFO - PROCESS | 1953 | RSS: Main: 329924608 14:54:10 INFO - PROCESS | 1953 | 14:54:12 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:50339/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50339/tests/kraken/driver.html?testName=json-parse-financial) 14:54:13 INFO - PROCESS | 1953 | RSS: Main: 329486336 14:54:13 INFO - PROCESS | 1953 | 14:54:15 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:50339/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50339/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:54:15 INFO - PROCESS | 1953 | RSS: Main: 312471552 14:54:15 INFO - PROCESS | 1953 | 14:54:17 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:50339/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50339/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:54:18 INFO - PROCESS | 1953 | RSS: Main: 320049152 14:54:18 INFO - PROCESS | 1953 | 14:54:20 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:50339/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50339/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:54:21 INFO - PROCESS | 1953 | RSS: Main: 321531904 14:54:21 INFO - PROCESS | 1953 | 14:54:23 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:50339/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50339/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:54:24 INFO - PROCESS | 1953 | RSS: Main: 336949248 14:54:24 INFO - PROCESS | 1953 | 14:54:27 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:50339/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50339/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:54:28 INFO - PROCESS | 1953 | RSS: Main: 333230080 14:54:28 INFO - PROCESS | 1953 | 14:54:29 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:50339/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50339/tests/kraken/driver.html?testName=ai-astar) 14:54:30 INFO - PROCESS | 1953 | RSS: Main: 376938496 14:54:30 INFO - PROCESS | 1953 | 14:54:30 INFO - PROCESS | 1953 | __start_tp_report 14:54:30 INFO - PROCESS | 1953 | _x_x_mozilla_page_load 14:54:30 INFO - PROCESS | 1953 | _x_x_mozilla_page_load_details 14:54:30 INFO - PROCESS | 1953 | |i|pagename|runs| 14:54:30 INFO - PROCESS | 1953 | |0;ai-astar;101;113;113;113;113;143;112;114;113;113 14:54:30 INFO - PROCESS | 1953 | |1;audio-beat-detection;175;146;118;165;117;123;170;136;181;118 14:54:30 INFO - PROCESS | 1953 | |2;audio-dft;182;184;184;181;181;183;184;189;183;180 14:54:30 INFO - PROCESS | 1953 | |3;audio-fft;79;72;57;110;57;57;61;64;97;56 14:54:30 INFO - PROCESS | 1953 | |4;audio-oscillator;86;89;93;72;72;69;95;71;68;102 14:54:30 INFO - PROCESS | 1953 | |5;imaging-gaussian-blur;87;84;86;84;89;88;87;84;86;83 14:54:30 INFO - PROCESS | 1953 | |6;imaging-darkroom;92;93;92;93;92;93;101;93;91;91 14:54:30 INFO - PROCESS | 1953 | |7;imaging-desaturate;86;85;86;83;86;88;70;130;141;124 14:54:30 INFO - PROCESS | 1953 | |8;json-parse-financial;56;56;56;54;54;54;53;55;55;54 14:54:30 INFO - PROCESS | 1953 | |9;json-stringify-tinderbox;66;77;79;77;72;70;69;70;72;72 14:54:30 INFO - PROCESS | 1953 | |10;stanford-crypto-aes;83;78;80;80;87;75;97;104;122;98 14:54:30 INFO - PROCESS | 1953 | |11;stanford-crypto-ccm;135;137;136;135;137;134;138;137;136;140 14:54:30 INFO - PROCESS | 1953 | |12;stanford-crypto-pbkdf2;174;169;171;172;170;173;175;172;170;173 14:54:30 INFO - PROCESS | 1953 | |13;stanford-crypto-sha256-iterative;68;66;67;67;67;68;67;66;66;66 14:54:30 INFO - PROCESS | 1953 | __end_tp_report 14:54:30 INFO - PROCESS | 1953 | __start_cc_report 14:54:30 INFO - PROCESS | 1953 | _x_x_mozilla_cycle_collect,2003 14:54:30 INFO - PROCESS | 1953 | __end_cc_report 14:54:30 INFO - PROCESS | 1953 | __startTimestamp1467150870078__endTimestamp 14:54:30 INFO - PROCESS | 1953 | 14:54:30 INFO - PROCESS | 1953 | ------- Summary: start ------- 14:54:30 INFO - PROCESS | 1953 | Number of tests: 14 14:54:30 INFO - PROCESS | 1953 | 14:54:30 INFO - PROCESS | 1953 | [#0] ai-astar Cycles:10 Average:114.80 Median:113.00 stddev:10.61 (9.4%) stddev-sans-first:10.01 14:54:30 INFO - PROCESS | 1953 | Values: 101.0 113.0 113.0 113.0 113.0 143.0 112.0 114.0 113.0 113.0 14:54:30 INFO - PROCESS | 1953 | 14:54:30 INFO - PROCESS | 1953 | [#1] audio-beat-detection Cycles:10 Average:144.90 Median:155.50 stddev:25.88 (16.6%) stddev-sans-first:25.06 14:54:30 INFO - PROCESS | 1953 | Values: 175.0 146.0 118.0 165.0 117.0 123.0 170.0 136.0 181.0 118.0 14:54:30 INFO - PROCESS | 1953 | 14:54:30 INFO - PROCESS | 1953 | [#2] audio-dft Cycles:10 Average:183.10 Median:183.50 stddev:2.51 (1.4%) stddev-sans-first:2.64 14:54:30 INFO - PROCESS | 1953 | Values: 182.0 184.0 184.0 181.0 181.0 183.0 184.0 189.0 183.0 180.0 14:54:30 INFO - PROCESS | 1953 | 14:54:30 INFO - PROCESS | 1953 | [#3] audio-fft Cycles:10 Average:71.00 Median:62.50 stddev:18.93 (30.3%) stddev-sans-first:19.85 14:54:30 INFO - PROCESS | 1953 | Values: 79.0 72.0 57.0 110.0 57.0 57.0 61.0 64.0 97.0 56.0 14:54:30 INFO - PROCESS | 1953 | 14:54:30 INFO - PROCESS | 1953 | [#4] audio-oscillator Cycles:10 Average:81.70 Median:79.00 stddev:12.65 (16.0%) stddev-sans-first:13.32 14:54:30 INFO - PROCESS | 1953 | Values: 86.0 89.0 93.0 72.0 72.0 69.0 95.0 71.0 68.0 102.0 14:54:30 INFO - PROCESS | 1953 | 14:54:30 INFO - PROCESS | 1953 | [#5] imaging-gaussian-blur Cycles:10 Average:85.80 Median:86.50 stddev:1.99 (2.3%) stddev-sans-first:2.06 14:54:30 INFO - PROCESS | 1953 | Values: 87.0 84.0 86.0 84.0 89.0 88.0 87.0 84.0 86.0 83.0 14:54:30 INFO - PROCESS | 1953 | 14:54:30 INFO - PROCESS | 1953 | [#6] imaging-darkroom Cycles:10 Average:93.10 Median:92.50 stddev:2.88 (3.1%) stddev-sans-first:3.03 14:54:30 INFO - PROCESS | 1953 | Values: 92.0 93.0 92.0 93.0 92.0 93.0 101.0 93.0 91.0 91.0 14:54:30 INFO - PROCESS | 1953 | 14:54:30 INFO - PROCESS | 1953 | [#7] imaging-desaturate Cycles:10 Average:97.90 Median:85.50 stddev:24.17 (28.3%) stddev-sans-first:25.25 14:54:30 INFO - PROCESS | 1953 | Values: 86.0 85.0 86.0 83.0 86.0 88.0 70.0 130.0 141.0 124.0 14:54:30 INFO - PROCESS | 1953 | 14:54:30 INFO - PROCESS | 1953 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:55.00 stddev:1.06 (1.9%) stddev-sans-first:1.01 14:54:30 INFO - PROCESS | 1953 | Values: 56.0 56.0 56.0 54.0 54.0 54.0 53.0 55.0 55.0 54.0 14:54:30 INFO - PROCESS | 1953 | 14:54:30 INFO - PROCESS | 1953 | [#9] json-stringify-tinderbox Cycles:10 Average:72.40 Median:72.00 stddev:4.09 (5.7%) stddev-sans-first:3.62 14:54:30 INFO - PROCESS | 1953 | Values: 66.0 77.0 79.0 77.0 72.0 70.0 69.0 70.0 72.0 72.0 14:54:30 INFO - PROCESS | 1953 | 14:54:30 INFO - PROCESS | 1953 | [#10] stanford-crypto-aes Cycles:10 Average:90.40 Median:81.50 stddev:14.75 (18.1%) stddev-sans-first:15.40 14:54:30 INFO - PROCESS | 1953 | Values: 83.0 78.0 80.0 80.0 87.0 75.0 97.0 104.0 122.0 98.0 14:54:30 INFO - PROCESS | 1953 | 14:54:30 INFO - PROCESS | 1953 | [#11] stanford-crypto-ccm Cycles:10 Average:136.50 Median:137.00 stddev:1.72 (1.3%) stddev-sans-first:1.73 14:54:30 INFO - PROCESS | 1953 | Values: 135.0 137.0 136.0 135.0 137.0 134.0 138.0 137.0 136.0 140.0 14:54:30 INFO - PROCESS | 1953 | 14:54:30 INFO - PROCESS | 1953 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:172.50 stddev:1.91 (1.1%) stddev-sans-first:1.87 14:54:30 INFO - PROCESS | 1953 | Values: 174.0 169.0 171.0 172.0 170.0 173.0 175.0 172.0 170.0 173.0 14:54:30 INFO - PROCESS | 1953 | 14:54:30 INFO - PROCESS | 1953 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:0.79 (1.2%) stddev-sans-first:0.71 14:54:30 INFO - PROCESS | 1953 | Values: 68.0 66.0 67.0 67.0 67.0 68.0 67.0 66.0 66.0 66.0 14:54:30 INFO - PROCESS | 1953 | -------- Summary: end -------- 14:54:30 INFO - PROCESS | 1953 | 14:54:30 INFO - TEST-INFO | 1953: exit 0 14:54:30 INFO - TEST-OK | kraken | took 58890ms 14:54:30 INFO - SUITE-END | took 1175s 14:54:31 INFO - Completed test suite (00:19:35) 14:54:31 INFO - javascript benchmark 14:54:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3361.865525378985, 3366.4613661814114, 3376.0040787967546, 3398.762086653935, 3428.657134286572, 5640.858978525529, 5732.770689172428, 5734.856628584283, 5760.279965004374, 5800.738966746501, 5666.405027472118, 5686.857828554288, 5824.475797178253, 5825.854353641151, 5872.765089396419, 5175.094358487268, 5199.66202196856, 5246.7901283948595, 5259.289995850569, 5395.865103372408, 4450.443694538183, 5346.599005074616, 5370.597205209606, 5396.730163491829, 5413.133898576229, 5175.120229560984, 5234.110201265756, 5250.684958902478, 5270.499302566269, 5288.10102282609, 5203.583713302926, 5229.111051121318, 5274.103402421598, 5275.76259068339, 5294.973525132388, 5203.167493201107, 5205.453427390107, 5206.713630750307, 5243.10867152585, 5301.257823904656, 5758.683272420015, 5786.016377215883, 5809.564282678773, 5844.824655260349, 5868.647881127104, 6116.640127388518, 6234.87530249397, 6321.273053598803, 6327.5570710050315, 6346.524010699216, 6300.149479820211, 6308.684565771693, 6381.968090159566, 6399.712012959428, 6402.93597064028, 5747.1379293106365, 5902.852428689274, 5945.940540594539, 5958.165856780054, 5958.940410595926, 6250.218722659668, 6304.306526282106, 6312.0, 6347.174867267226, 6357.650329231844, 6194.5044396448175, 6234.251889773165, 6249.75000999964, 6255.218097737783, 6278.46633036197, 6129.542179248642, 6200.286966998763, 6290.811275661737, 6320.462760665395, 6327.0, 6241.78153764616, 6318.0, 6338.080978258219, 6338.397852204033, 6350.174477317919, 6295.118683384329, 6356.45970092538, 6366.204224471941, 6370.331115232926, 6388.009858471944, 4122.9381559276635, 4946.183879659888, 4997.425296090923, 5005.524475174852, 5041.0422019816115, 4422.314541246112, 4429.49060857999, 4460.531644177379, 4467.30756732707, 4491.977540112279, 5790.305163380422, 5818.069108942549, 5825.79609713658, 5827.067669172912, 5881.676507792027, 6119.776756598045, 6249.437550620466, 6263.185785847811, 6339.587926784744, 6380.585261957958, 5793.2179155814265, 5794.478496935296, 5812.331581868055, 5812.505936995402, 5827.446392592697, 5453.7818487260065, 5454.645448045864, 5456.154296084113, 5462.562994960394, 5478.369987451414, 6283.748650054038, 6304.18045654062, 6364.777232796922, 6365.649889256136, 6415.973444248898, 6299.590526615755, 6364.745410183726, 6368.42684158428, 6371.649559274284, 6371.96814015927, 6400.647964362005, 6406.167198264196, 6417.6470294132405, 6430.871382572229, 6436.324185960594, 6110.388961103854, 6133.73253493014, 6146.754129834757, 6225.035119556476, 6246.281677607133, 6346.333634968447, 6347.936520634735, 6356.0, 6357.237131544244, 6369.01280301554, 6192.659403732838, 6214.4407003369915, 6216.595921265103, 6218.968905155445, 6222.471089957405, 6010.3088144863905, 6085.786997455068, 6101.913673707429, 6123.755049797958, 6134.069235337226, 5878.500327472042, 6053.334133245251, 6054.182685337423, 6055.182550355822, 6062.144193638225, 6183.84540386476, 6214.9067763984485, 6217.347178546368, 6230.7819226326865, 6236.438720515175, 5858.502027327725, 5902.2622172228475, 5948.762049518144, 6017.608855424383, 6036.939630603813], "value": 5764.584433119745, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [485.45386440254714, 493.9975300123499, 512.6795752654591, 514.570333771301, 544.6541446181675, 30498.390032199342, 41353.17293654129, 42109.78945105274, 42144.0, 42232.0, 42290.0, 42584.57415425845, 42676.78661606695, 42707.572924270746, 42822.357664635056, 8792.296616270714, 9346.299027572924, 9509.191718703918, 9529.714108576754, 9534.427934323963, 8796.780080498007, 9944.204463642891, 12110.455029523648, 12118.515259389615, 12276.140670153092, 11968.581099661513, 12038.81941770874, 12048.096392770578, 12053.578124765634, 12082.637520874345, 8884.689035883777, 11861.46623401949, 11991.340476273801, 12079.999999999956, 12191.963683086948, 11734.882651173464, 11789.997850182745, 11827.526898924032, 11830.0, 11839.467223974942, 40230.0, 40614.0, 40621.593784062374, 40638.56168629892, 40694.38958415626, 3418.2308980479193, 3420.6066302375298, 3425.743069269815, 3457.429524128516, 3535.505029295901, 30725.924592639374, 31262.21844453884, 32604.021879343658, 32683.0, 32716.672833271838, 2909.3890283040587, 3368.2421455172635, 3371.089805752458, 3419.230673098558, 3432.5194472773583, 516419.0, 521168.0, 522117.0, 522567.0, 522716.0, 62243.0, 62608.0, 62704.68647656824, 62797.0, 62799.68600157061, 63441.0, 63654.0, 63822.0, 63902.680486597266, 64048.0, 16500.092494912897, 16512.25694843735, 16710.4151354702, 16725.581860453585, 16744.58138546522, 11763.0, 11965.940170299093, 12755.107142500121, 12852.100352975389, 12940.482380704854, 18471.445856624323, 18491.722624160648, 18551.90724046371, 18552.628947420977, 18561.44315670532, 6821.897671534931, 8121.959390203011, 8304.501729896225, 8419.36854735885, 8451.323894088458, 916.3951791817502, 924.7872989212519, 1005.4017859373661, 1041.286718597763, 1055.6094245129352, 26383.0, 28560.85719571389, 30175.698243017727, 30876.845615771777, 30955.0713478596, 1041.6250149946013, 1093.3439936038314, 1095.1512577752305, 1160.640201537526, 1168.4391492083848, 13791.379387927367, 15630.3747850087, 15657.29542170605, 15728.0, 15746.291416886268, 724421.0, 726476.0, 730030.0, 730061.0, 731057.0, 58015.0, 59744.22046236291, 59815.102773458435, 59842.40157598456, 59973.70013149906, 1258.993705031469, 1296.2870421268265, 1391.2765362011899, 1395.958121256364, 1418.7375335562954, 30063.849680751457, 31063.68936310608, 31489.60291178116, 31528.684713153492, 31827.522587161675, 48499.51500485091, 49366.75316623537, 49425.75287123541, 49531.50468495269, 49543.0, 458959.0, 494049.0, 495462.0, 495526.0, 497413.0, 22978.540429190987, 25640.61539076878, 25869.09458168955, 25947.0, 26476.86761566257, 47280.0, 47877.0, 48396.0, 48464.7576762128, 48839.75580122077, 34480.62077516871, 38766.812695041655, 39666.40500392439, 40083.99790005273, 40272.1945561093, 37877.81061094677, 38342.23315533729, 38668.80665596654, 39217.80391098026, 39451.21097577975, 37712.24575508529, 38156.2368752629, 38489.42265865958, 38506.80746596361, 38612.80693596626, 33974.49038264379, 34074.0, 34909.65090349064, 34922.47616285809, 35176.296474070885, 23766.881165594063, 25222.62166067547, 25431.982720690965, 25598.872005639852, 26259.949602015706], "value": 20551.594590296667, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.78533413955245, 387.891390410685, 393.04882185112007, 393.83783031158913, 413.6835320979449, 20681.896590517026, 21148.154073837046, 21677.916104194806, 21856.344309670694, 21879.99999999998, 19942.70085948712, 20316.67941583796, 21053.894730526365, 21084.156633734634, 22447.663285050698, 5923.970380148104, 6188.257409110913, 6200.937990620081, 6243.282022567395, 6247.937520624804, 6035.245594300713, 6083.148359229712, 6110.511159107261, 6222.346653601375, 6233.127362169277, 6153.969230153843, 6173.506119510428, 6188.907166392508, 6201.534884883629, 6213.440790328869, 5824.621399609033, 6084.209052823105, 6087.847803804874, 6096.603720758137, 6186.505079593644, 5268.525832675039, 5274.92087618686, 5283.286756287929, 5284.947150528523, 5290.153575427913, 22183.66724499134, 27080.32299192516, 27564.724352756617, 27652.58521122185, 27670.031548895895, 2272.4659704969317, 2333.241696448644, 2344.284993077111, 2360.386299562109, 3003.5044217704053, 15774.921125394414, 15963.920180399024, 16083.839161608352, 16233.350665973347, 16763.66472670552, 1878.3998889224981, 1894.4506093232947, 1915.4636701723539, 1951.0439884456514, 1968.0651690447066, 74813.62593186999, 84535.577322113, 123162.76837231692, 124590.0, 125435.3728231353, 10947.124230061736, 11246.550137994389, 11440.885591143982, 11449.71375715597, 11599.130065244972, 14160.433582656784, 14234.572962810926, 14293.78559321611, 14298.785518217235, 14336.354864031144, 8607.784805379915, 8773.561321933877, 8792.780180495538, 8867.334949878785, 8905.243054340453, 7658.617069146632, 8482.727590861421, 8732.869006964773, 8752.387332886765, 8869.68956086534, 5843.3864444233595, 6813.420859226922, 6910.412614927787, 6914.51598388108, 6930.896036559456, 1706.743673756505, 1711.8544923681377, 1855.3691744806831, 1924.8363889069585, 1939.21461807968, 908.3868388837508, 934.8504239321676, 991.9435800872049, 1013.5236438873718, 1033.390299723167, 22387.552248955257, 23061.53876922452, 23141.652875206884, 23259.534809303717, 23293.650595241084, 904.6291020681489, 984.8965858584888, 1009.2632378363835, 1021.7547788530848, 1076.9192310576739, 10839.0, 11994.940025299993, 12083.637490875288, 12118.939405302917, 12271.570495032809, 537961.0, 592104.0, 600103.9994799998, 600817.0, 609917.0, 79612.0, 115933.0, 122132.0, 124628.0, 127826.0, 960.7790208252142, 982.6953644370268, 991.5290237137447, 1012.5038731021747, 1012.5443550402348, 19875.801241987974, 20500.589988200452, 20519.58960820805, 20550.486237844172, 20555.4861128473, 49906.500934991636, 51658.74170629123, 51927.48072519226, 52490.68773280698, 52728.762414821016, 72679.90980135408, 72743.90884136848, 72934.82567353365, 73076.63461682657, 73284.90072648808, 6908.412784913137, 7341.6696248670005, 7467.806444203731, 7647.349975252166, 8181.836363272821, 11757.529698812294, 13440.865591344353, 13504.797428038784, 13656.0, 13815.032947693566, 11066.502007409847, 11147.498362573866, 11704.297742135499, 11800.586979455678, 11980.520779168735, 11633.6509904703, 11661.708457288636, 11668.0, 11675.3578553177, 11862.58480953197, 11328.0, 11851.288922664668, 11858.881411185777, 12095.455704493495, 12132.150749447454, 8048.301736775505, 8417.663293468193, 8456.83086338282, 8494.32045436376, 8565.357598180286, 8478.788030299045, 10435.634752783617, 11647.068234541048, 11677.76644467089, 11984.28094314344], "value": 9951.680491988685, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1385.528920167143, 1420.4886240953256, 1556.5719427157535, 1774.8402643762058, 1971.5366888781134, 7284.890726639098, 7966.641501132449, 9001.324900632462, 9014.819703605925, 9086.909130908689, 8661.263792577638, 8873.378863479558, 8886.333524985635, 8893.466392016484, 8894.77763055924, 2098.7271654684946, 2180.6292930201826, 2182.4980254541465, 2372.3238876920036, 2408.385861605288, 2143.65701487762, 2205.151016858513, 2337.719473663154, 2435.585950388438, 2436.549238390903, 2366.8106551475926, 2396.0, 2399.4961058177805, 2400.2799160251943, 2406.626972819216, 1969.6257711034857, 2078.511549785799, 2364.609839376501, 2366.8106551475926, 2366.857988520677, 2318.4435735423544, 2399.8560086394873, 2404.0, 2414.2877851033813, 2414.6015907375263, 9014.459132452073, 9087.136722011399, 9095.0, 9097.408668436598, 9100.135487128713, 5250.553702935217, 5428.809991650273, 5432.076546987022, 5435.130379139358, 5447.645903016291, 3478.3565040467342, 3584.301061293049, 3609.873654422083, 3656.7988760618155, 3659.743817932746, 1045.2265323660513, 1065.5737704918017, 1069.6256310291335, 1087.1085709718034, 1094.8467214589964, 12009.519619215222, 12033.63899083029, 12044.0, 12051.0, 12054.638360849101, 4708.623310135181, 4756.9762151189025, 4851.490593487703, 4974.552290293891, 4983.90032199354, 9323.207527360115, 9359.29805264608, 9460.668876589287, 9462.52687365629, 9463.574139163755, 5331.040412725747, 5331.200319952039, 5333.359996800331, 5334.786608535693, 5339.14573668219, 4801.855944321676, 4987.526185012418, 4997.350344455198, 5000.799968001312, 5007.349044624249, 3656.3601369760186, 3700.3339398908456, 3764.8682296119505, 3785.4511095890944, 3806.314863324573, 1129.4239937631703, 1139.316410153901, 1142.0520967596874, 1151.1193936638479, 1151.159653452984, 823.2097186700714, 897.4077109107957, 916.3539704508439, 939.3987847777428, 954.1365064616533, 5979.730912108966, 6058.1215723719815, 6115.388461153938, 6224.533160013018, 6312.084747711556, 936.3539157980971, 954.7613096725819, 983.095552092077, 983.6803039012207, 983.8868530118984, 3672.247189326182, 3733.9731573817417, 3836.1368692044625, 4003.599640035973, 4042.130941847517, 10199.28604997658, 10483.318584291997, 10891.509882055327, 10908.781824363468, 10911.07255883243, 10586.0, 10702.411367374869, 10810.243282970274, 10840.295380800222, 10848.620298289521, 7865.921340786634, 8141.755747327589, 8307.626156822958, 8328.250457458858, 8378.539180345018, 6007.158997740233, 6128.264608247039, 6130.141780150693, 6229.221347331583, 6249.96875015622, 9677.903220967983, 10204.13264872494, 10218.437985910548, 10328.793424131325, 10345.017223363768, 9258.0, 9280.118388753057, 9673.854892176481, 10393.012663796928, 10397.064264216257, 6988.650567471708, 7173.569585824867, 7179.246179151114, 7199.568025918462, 7233.891491627735, 7111.146662400545, 8400.28597569189, 8494.277986371228, 8507.957460212907, 8519.574021299035, 7619.65711542993, 7682.193369696101, 7686.231376862269, 7801.1808760079375, 7817.296443320129, 7423.072115985502, 7503.324700776738, 7575.242475752601, 7602.163761986067, 7721.227877212234, 7055.082839230867, 7064.293570642894, 7106.395956343768, 7107.75122870697, 7151.070360853056, 4489.865304040884, 4954.5788607968725, 4976.527229913152, 5055.418626857959, 5106.744662766772, 4441.755703436342, 5038.899222015612, 5084.669496482717, 5103.770330335218, 5179.1195496766395], "value": 4591.678403515657, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.21097989147785, 312.51560081873106, 316.8257458397882, 322.21219119253436, 323.88178314915035, 4457.754823484707, 5104.438511763703, 5374.946250537499, 5388.137897936331, 5394.244805727191, 5073.1375666136755, 5346.786128554862, 5365.0, 5386.656804290136, 5392.649477783942, 3272.296456261903, 3408.113890388492, 3659.5425571803526, 3866.5360156781226, 4022.153305267146, 3275.525048867925, 3548.982255088734, 4076.286649836267, 4089.243489954353, 4109.56849530798, 3277.3936821687944, 4030.415589739486, 4040.616141466556, 4065.0, 4145.751254924699, 3851.28751181031, 3976.5426975897562, 3981.323175060232, 4095.7747323897174, 4263.445752052245, 3533.2933413317182, 3538.840752166133, 3549.402768754071, 3573.910652233715, 3578.6063533011347, 4265.936010959838, 4689.8358557450665, 5064.670796398223, 5084.872878178038, 5200.453952334989, 4109.56849530798, 4174.666026717855, 4420.359047938063, 4424.292113261863, 4485.596296333313, 4771.928421073687, 4944.283078953567, 5602.467765562265, 5631.549476041907, 5661.971690141564, 3936.0356712605594, 3944.7238693291483, 3953.6639385652256, 3956.742811717258, 3987.760734355948, 5425.6202065855805, 5571.526420254283, 5896.764129434818, 5921.911171332434, 5951.137085122677, 3413.4026545354463, 3435.209901722618, 3958.2281455115976, 3986.126520535344, 4018.0758425562285, 4054.71616986814, 4063.349864021742, 4063.8577649782706, 4087.46862907826, 4119.711620186558, 3679.68722658577, 4175.519815221228, 4297.505786834554, 4704.223803072523, 4704.764761761899, 5000.574951129195, 5604.971975140098, 5625.0, 5696.11710184922, 5755.366909639936, 1204.8914399800349, 1251.5431867368527, 1329.335332333833, 1367.6375760423496, 1403.4316101978716, 790.3440144679902, 813.8819871118654, 848.499385362639, 878.4685265414332, 887.5384799904143, 4492.752898590544, 4834.854954351375, 4907.993861258434, 5252.527272545412, 5264.42091369949, 5753.8066373162455, 5784.161296612052, 5795.333536643256, 5805.564582656318, 5808.6224395414165, 4684.297355396718, 5009.699418034929, 5232.869178270574, 5265.973670131625, 5289.70906600133, 2698.9595156072673, 2766.806323557372, 2832.895170883357, 2995.9101226963226, 3038.68093850147, 5171.276021357013, 5317.73411329432, 5344.786208551692, 5827.0, 6026.306974697878, 4619.729574367008, 5316.368397643191, 5661.688607126565, 5974.522038236931, 6007.399260074045, 5642.943570564406, 5647.209390685225, 5810.331811841608, 6069.722349931076, 6136.9079463808075, 5090.821821236239, 5328.653637513549, 5867.970660146672, 5879.941610509978, 5904.557158213065, 4910.410750709795, 5833.416658334133, 5836.708164591839, 5880.7065244553805, 5915.290165180206, 5179.689218646893, 6093.116498107661, 6122.540809439399, 6141.877162456815, 6192.164057852132, 3773.7924414157483, 3856.36369998947, 3869.342211824053, 3888.805559721946, 3912.237113762789, 5829.3296270927685, 5863.824085277449, 5874.823755287348, 5888.174665853599, 5901.527877769682, 4702.741349225688, 5285.365756109291, 5451.836444906659, 5493.945060549503, 5727.140928860705, 4828.951710482991, 5507.5593952484505, 5530.695811730394, 5554.222408862682, 5676.318841739017, 4927.90564010287, 5137.9486205138965, 5518.972405137949, 5533.391326953952, 5586.553075753849, 4597.908041839211, 4951.5048495150195, 5399.676019438846, 5426.751847075116, 5570.359408667893, 5339.786408543615, 5542.944570554243, 5554.722413844757, 5567.4432556744005, 5569.721513924369], "value": 4083.6817676598944, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [707.6214225389415, 711.9038929744485, 722.6206241723091, 779.7621725373758, 851.4210336970857, 15322.54032379029, 15901.761473577906, 15949.601259968507, 15953.601159971007, 16068.116253606047, 13007.869921300784, 13080.73838523229, 13085.803712944313, 13106.80339794904, 13110.803337949937, 711.8540699156661, 738.8079099434159, 738.8263758016861, 895.1809094678378, 908.9091090890925, 733.3033618062861, 739.6708464733196, 884.4737381258174, 913.0458670689146, 916.839553078212, 961.4567769210408, 969.0067680627343, 972.479723348009, 973.4159504297436, 975.6438899801557, 970.854371844222, 971.03382134776, 973.8003709239589, 974.0016483104874, 975.024975024968, 558.2212813125667, 695.09984569982, 714.8713231618307, 715.309726114297, 723.9095113110861, 15491.225438728019, 15513.844861551466, 15548.533543993699, 15554.377824886991, 15563.37746490139, 12102.653690202593, 14034.719305613933, 14351.928240358835, 14491.927540362336, 14748.262586870613, 12628.242305461701, 12827.807582886264, 13087.0, 13502.594922152352, 13607.863921360758, 4575.130725162208, 4598.218302888484, 4608.516105808875, 4761.309610106549, 4785.162596545625, 113713.0, 115555.0, 118001.0, 118370.40814795958, 118602.99999999914, 11384.316940983568, 11391.829122563167, 11420.494981013977, 11504.367259800623, 11536.365499897585, 23103.960321785224, 23364.948577314062, 23372.0, 23507.529849402916, 23617.0, 8105.432619716681, 8143.75568732939, 8180.672773089129, 8187.38594605407, 8223.917760822435, 3658.213484100878, 4266.530681625019, 4367.039251364695, 4439.42287502629, 4512.616427603625, 3527.5414196154848, 3603.531540899666, 3644.03433090226, 3646.744727869024, 3652.5069115669576, 642.1010585180784, 645.257953353647, 645.8449972006779, 659.818924125819, 668.749219042859, 550.5430492691057, 599.6312267955263, 613.365167052099, 639.6290151711996, 645.1902861908275, 9111.954440227757, 9159.221466175317, 9207.631694732136, 9279.304052196112, 9378.202852757455, 571.7541457173455, 581.8080033588906, 610.697704636208, 634.8698516804044, 647.3494138390887, 4040.313146765057, 4404.361367601679, 4648.604868586208, 4743.549362810528, 4799.400074990626, 139752.0, 147915.52084479018, 154313.0, 155009.0, 158781.0, 121329.0, 122142.38928805299, 122890.0, 123928.0, 124857.0, 110455.0, 112771.43614281875, 114445.42777286227, 114455.0, 117517.0, 9024.0, 9173.678921237724, 9182.357234993486, 9208.585613647536, 9449.763755906157, 60966.0, 61484.385156147866, 62242.68878655759, 62794.0, 63452.365476346495, 94128.0, 95797.0, 96726.0, 97412.51293743486, 99090.0, 11720.355380454157, 11833.881661183277, 12040.578579749666, 12526.574161202298, 12606.810897836356, 11360.431978401211, 13644.522441714493, 13680.794788077988, 13719.342804984744, 14245.786313205102, 12286.508539658313, 12532.937335313265, 12706.36468176606, 12745.235285882876, 13087.41106650222, 10820.458977051274, 12503.312317822607, 12535.93732031334, 12763.425645846144, 13225.669358266121, 10646.414447205478, 12105.757884842078, 12217.389130543615, 12319.87680123223, 12786.744265114832, 10405.58377664885, 10517.286093322875, 10580.629677961235, 10982.780344393228, 11040.392778397265, 10566.682999510027, 10589.0, 10660.25378223517, 10806.783864322511, 10825.188110891557], "value": 7825.350398921018, "unit": "score"}], "name": "dromaeo_css", "value": 7464.729793370007, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 113.0, 113.0, 113.0, 143.0, 112.0, 114.0, 113.0, 113.0], "value": 114.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 146.0, 118.0, 165.0, 117.0, 123.0, 170.0, 136.0, 181.0, 118.0], "value": 144.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 184.0, 181.0, 181.0, 183.0, 184.0, 189.0, 183.0, 180.0], "value": 183.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 72.0, 57.0, 110.0, 57.0, 57.0, 61.0, 64.0, 97.0, 56.0], "value": 71.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 93.0, 72.0, 72.0, 69.0, 95.0, 71.0, 68.0, 102.0], "value": 81.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 86.0, 84.0, 89.0, 88.0, 87.0, 84.0, 86.0, 83.0], "value": 85.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 92.0, 93.0, 92.0, 93.0, 101.0, 93.0, 91.0, 91.0], "value": 93.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 83.0, 86.0, 88.0, 70.0, 130.0, 141.0, 124.0], "value": 97.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 54.0, 54.0, 54.0, 53.0, 55.0, 55.0, 54.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 77.0, 79.0, 77.0, 72.0, 70.0, 69.0, 70.0, 72.0, 72.0], "value": 72.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 78.0, 80.0, 80.0, 87.0, 75.0, 97.0, 104.0, 122.0, 98.0], "value": 90.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 137.0, 136.0, 135.0, 137.0, 134.0, 138.0, 137.0, 136.0, 140.0], "value": 136.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 169.0, 171.0, 172.0, 170.0, 173.0, 175.0, 172.0, 170.0, 173.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 67.0, 67.0, 68.0, 67.0, 66.0, 66.0, 66.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1465.0, "alertThreshold": 2.0}]} 14:54:31 INFO - Return code: 0 14:54:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:54:31 INFO - # TBPL SUCCESS # 14:54:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:54:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:54:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:54:31 INFO - 2016-06-28 14:54:31.373 screenresolution[1957:13626] starting screenresolution argv=screenresolution get 14:54:31 INFO - 2016-06-28 14:54:31.376 screenresolution[1957:13626] Display 0: 1600x1200x32@60 14:54:31 INFO - 2016-06-28 14:54:31.382 screenresolution[1958:13629] starting screenresolution argv=screenresolution list 14:54:31 INFO - Available Modes on Display 0 14:54:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:54:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:54:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:54:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:54:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:54:31 INFO - Intel Iris: 14:54:31 INFO - Chipset Model: Intel Iris 14:54:31 INFO - Type: GPU 14:54:31 INFO - Bus: Built-In 14:54:31 INFO - VRAM (Dynamic, Max): 1536 MB 14:54:31 INFO - Vendor: Intel (0x8086) 14:54:31 INFO - Device ID: 0x0a2e 14:54:31 INFO - Revision ID: 0x0009 14:54:31 INFO - Displays: 14:54:31 INFO - Display: 14:54:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:54:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:54:31 INFO - Display Serial Number: 1600x1200 60 14:54:31 INFO - Main Display: Yes 14:54:31 INFO - Mirror: Off 14:54:31 INFO - Online: Yes 14:54:31 INFO - Rotation: Supported 14:54:31 INFO - Return code: 0 14:54:31 INFO - Running post-action listener: _resource_record_post_action 14:54:31 INFO - ##### 14:54:31 INFO - ##### Finished run-tests step (success) 14:54:31 INFO - ##### 14:54:31 INFO - Running post-run listener: _resource_record_post_run 14:54:31 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 1110016; Write bytes: 707087360; Read time: 14; Write time: 1891 14:54:31 INFO - TinderboxPrint: CPU usage
53.6% 14:54:31 INFO - TinderboxPrint: I/O read bytes / time
1,110,016 / 14 14:54:31 INFO - TinderboxPrint: I/O write bytes / time
707,087,360 / 1,891 14:54:31 INFO - TinderboxPrint: CPU idle
2,246.2 (46.4%) 14:54:31 INFO - TinderboxPrint: CPU system
965.4 (19.9%) 14:54:31 INFO - TinderboxPrint: CPU user
1,634.0 (33.7%) 14:54:31 INFO - TinderboxPrint: Swap in / out
408,240,128 / 0 14:54:31 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 400263680; Write bytes: 396985856; Read time: 30334; Write time: 769 14:54:31 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 1073152; Write bytes: 294094336; Read time: 13; Write time: 1080 14:54:32 INFO - Running post-run listener: _upload_blobber_files 14:54:32 INFO - Blob upload gear active. 14:54:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:54:32 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:54:32 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:54:32 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:54:32 INFO - (blobuploader) - INFO - Open directory for files ... 14:54:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:54:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:54:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:54:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:54:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:54:33 INFO - (blobuploader) - INFO - Done attempting. 14:54:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:54:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:54:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:54:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:54:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:54:34 INFO - (blobuploader) - INFO - Done attempting. 14:54:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:54:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:54:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:54:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:54:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:54:36 INFO - (blobuploader) - INFO - Done attempting. 14:54:36 INFO - (blobuploader) - INFO - Iteration through files over. 14:54:36 INFO - Return code: 0 14:54:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:54:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:54:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ca209f026a69aa7beb71d2e4d958c0f86887b2a615b1bfd1eb757962496bf104f9b8c40b743830b3c81100b8cee4844899299bc6acd674dee0c381fa5d280a20", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/efd618bb7be3d43e6ce512e2353a647898ca0b371a993087664646fe2598c184315aa7e71366c18ea56f3b778122bdfabe26b52c8a7c5c108937903b1d045c27", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b58d34f9b7db36a2689658c5855a040f19e262dd08351c0fa57282cc776c21a0cb5c27dd8c2cc6ab6c37fe51e67aca2ee99508ebd0531904461ab3b68e4fa3e8"} 14:54:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:54:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:54:36 INFO - Contents: 14:54:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ca209f026a69aa7beb71d2e4d958c0f86887b2a615b1bfd1eb757962496bf104f9b8c40b743830b3c81100b8cee4844899299bc6acd674dee0c381fa5d280a20", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/efd618bb7be3d43e6ce512e2353a647898ca0b371a993087664646fe2598c184315aa7e71366c18ea56f3b778122bdfabe26b52c8a7c5c108937903b1d045c27", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b58d34f9b7db36a2689658c5855a040f19e262dd08351c0fa57282cc776c21a0cb5c27dd8c2cc6ab6c37fe51e67aca2ee99508ebd0531904461ab3b68e4fa3e8"} 14:54:36 INFO - Running post-run listener: copy_logs_to_upload_dir 14:54:36 INFO - Copying logs to upload dir... 14:54:36 INFO - mkdir: /builds/slave/test/build/upload/logs 14:54:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1400.735936 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 20 secs) (at 2016-06-28 14:54:36.891930) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-28 14:54:36.895118) ========= 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.StQFBnmBcF/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.dfuHpyTCH8/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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/Autoland/sha512/ca209f026a69aa7beb71d2e4d958c0f86887b2a615b1bfd1eb757962496bf104f9b8c40b743830b3c81100b8cee4844899299bc6acd674dee0c381fa5d280a20", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/efd618bb7be3d43e6ce512e2353a647898ca0b371a993087664646fe2598c184315aa7e71366c18ea56f3b778122bdfabe26b52c8a7c5c108937903b1d045c27", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b58d34f9b7db36a2689658c5855a040f19e262dd08351c0fa57282cc776c21a0cb5c27dd8c2cc6ab6c37fe51e67aca2ee99508ebd0531904461ab3b68e4fa3e8"} build_url:https://queue.taskcluster.net/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011043 build_url: 'https://queue.taskcluster.net/v1/task/NO2u7GdyRu23QmWSyCoVpw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ca209f026a69aa7beb71d2e4d958c0f86887b2a615b1bfd1eb757962496bf104f9b8c40b743830b3c81100b8cee4844899299bc6acd674dee0c381fa5d280a20", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/efd618bb7be3d43e6ce512e2353a647898ca0b371a993087664646fe2598c184315aa7e71366c18ea56f3b778122bdfabe26b52c8a7c5c108937903b1d045c27", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b58d34f9b7db36a2689658c5855a040f19e262dd08351c0fa57282cc776c21a0cb5c27dd8c2cc6ab6c37fe51e67aca2ee99508ebd0531904461ab3b68e4fa3e8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-28 14:54:36.927999) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 14:54:36.928330) ========= 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.StQFBnmBcF/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.dfuHpyTCH8/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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.005115 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 14:54:36.976624) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-28 14:54:36.976981) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-28 14:54:36.977313) ========= ========= Total master_lag: 4.00 =========