builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0033 starttime: 1464834372.07 results: success (0) buildid: 20160601171646 builduid: 53752fd2ea174b3482276bb52bef4de6 revision: 279597150b922a9b762de1dc88fbf083a31a4f6f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 19:26:12.069480) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 19:26:12.070134) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 19:26:12.070411) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 19:26:12.255168) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 19:26:12.255460) ========= 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.xHCPsN9u09/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.aNgyg6Kdto/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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-01 19:26:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.4M=0.001s 2016-06-01 19:26:12 (15.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.525004 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 19:26:12.846162) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 19:26:12.846540) ========= 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.xHCPsN9u09/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.aNgyg6Kdto/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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.028239 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 19:26:12.895386) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-01 19:26:12.895758) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 279597150b922a9b762de1dc88fbf083a31a4f6f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 279597150b922a9b762de1dc88fbf083a31a4f6f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xHCPsN9u09/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.aNgyg6Kdto/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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-01 19:26:12,971 truncating revision to first 12 chars 2016-06-01 19:26:12,971 Setting DEBUG logging. 2016-06-01 19:26:12,971 attempt 1/10 2016-06-01 19:26:12,971 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/279597150b92?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-01 19:26:13,985 unpacking tar archive at: larch-279597150b92/testing/mozharness/ program finished with exit code 0 elapsedTime=1.268670 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-01 19:26:14.184198) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 19:26:14.184501) ========= script_repo_revision: 279597150b922a9b762de1dc88fbf083a31a4f6f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 19:26:14.184898) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 19:26:14.185158) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 19:26:14.201399) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 39 secs) (at 2016-06-01 19:26:14.201849) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xHCPsN9u09/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.aNgyg6Kdto/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 19:26:14 INFO - MultiFileLogger online at 20160601 19:26:14 in /builds/slave/test 19:26:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 19:26:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:26:14 INFO - {'append_to_log': False, 19:26:14 INFO - 'base_work_dir': '/builds/slave/test', 19:26:14 INFO - 'blob_upload_branch': 'Larch', 19:26:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:26:14 INFO - 'branch': 'Larch', 19:26:14 INFO - 'buildbot_json_path': 'buildprops.json', 19:26:14 INFO - 'config_files': ('talos/mac_config.py',), 19:26:14 INFO - 'default_actions': ('clobber', 19:26:14 INFO - 'read-buildbot-config', 19:26:14 INFO - 'download-and-extract', 19:26:14 INFO - 'populate-webroot', 19:26:14 INFO - 'create-virtualenv', 19:26:14 INFO - 'install', 19:26:14 INFO - 'run-tests'), 19:26:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:26:14 INFO - 'download_minidump_stackwalk': True, 19:26:14 INFO - 'download_symbols': 'ondemand', 19:26:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:26:14 INFO - 'tooltool.py': '/tools/tooltool.py', 19:26:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:26:14 INFO - '/tools/misc-python/virtualenv.py')}, 19:26:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:26:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:26:14 INFO - 'installer_path': 'installer.exe', 19:26:14 INFO - 'log_level': 'info', 19:26:14 INFO - 'log_name': 'talos', 19:26:14 INFO - 'log_to_console': True, 19:26:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:26:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:26:14 INFO - 'opt_config_files': (), 19:26:14 INFO - 'pip_index': False, 19:26:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:26:14 INFO - 'cmd': ('bash', 19:26:14 INFO - '-c', 19:26:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:26:14 INFO - 'enabled': True, 19:26:14 INFO - 'halt_on_failure': False, 19:26:14 INFO - 'name': 'check_screen_resolution'},), 19:26:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:26:14 INFO - 'cmd': ('bash', 19:26:14 INFO - '-c', 19:26:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:26:14 INFO - 'enabled': True, 19:26:14 INFO - 'halt_on_failure': False, 19:26:14 INFO - 'name': 'check_screen_resolution'},), 19:26:14 INFO - 'run_cmd_checks_enabled': True, 19:26:14 INFO - 'sps_profile': False, 19:26:14 INFO - 'sps_profile_interval': 0, 19:26:14 INFO - 'suite': 'dromaeojs', 19:26:14 INFO - 'system_bits': '32', 19:26:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:26:14 INFO - 'title': 't-yosemite-r7-0033', 19:26:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:26:14 INFO - 'use_talos_json': True, 19:26:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:26:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:26:14 INFO - 'work_dir': 'build'} 19:26:14 INFO - ##### 19:26:14 INFO - ##### Running clobber step. 19:26:14 INFO - ##### 19:26:14 INFO - Running pre-action listener: _resource_record_pre_action 19:26:14 INFO - Running main action method: clobber 19:26:14 INFO - rmtree: /builds/slave/test/build 19:26:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:26:16 INFO - Running post-action listener: _resource_record_post_action 19:26:16 INFO - ##### 19:26:16 INFO - ##### Finished clobber step (success) 19:26:16 INFO - ##### 19:26:16 INFO - ##### 19:26:16 INFO - ##### Running read-buildbot-config step. 19:26:16 INFO - ##### 19:26:16 INFO - Running pre-action listener: _resource_record_pre_action 19:26:16 INFO - Running main action method: read_buildbot_config 19:26:16 INFO - Using buildbot properties: 19:26:16 INFO - { 19:26:16 INFO - "project": "", 19:26:16 INFO - "product": "firefox", 19:26:16 INFO - "script_repo_revision": "production", 19:26:16 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 19:26:16 INFO - "repository": "", 19:26:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 19:26:16 INFO - "buildid": "20160601171646", 19:26:16 INFO - "pgo_build": "False", 19:26:16 INFO - "basedir": "/builds/slave/test", 19:26:16 INFO - "buildnumber": 1, 19:26:16 INFO - "slavename": "t-yosemite-r7-0033", 19:26:16 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 19:26:16 INFO - "platform": "yosemite_r7", 19:26:16 INFO - "scheduler": "tests-larch-macosx64-talos", 19:26:16 INFO - "branch": "larch", 19:26:16 INFO - "repo_path": "projects/larch", 19:26:16 INFO - "revision": "279597150b922a9b762de1dc88fbf083a31a4f6f", 19:26:16 INFO - "stage_platform": "macosx64", 19:26:16 INFO - "builduid": "53752fd2ea174b3482276bb52bef4de6", 19:26:16 INFO - "slavebuilddir": "test" 19:26:16 INFO - } 19:26:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 19:26:16 INFO - Running post-action listener: _resource_record_post_action 19:26:16 INFO - ##### 19:26:16 INFO - ##### Finished read-buildbot-config step (success) 19:26:16 INFO - ##### 19:26:16 INFO - ##### 19:26:16 INFO - ##### Running download-and-extract step. 19:26:16 INFO - ##### 19:26:16 INFO - Running pre-action listener: _resource_record_pre_action 19:26:16 INFO - Running main action method: download_and_extract 19:26:16 INFO - mkdir: /builds/slave/test/build/tests 19:26:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:26:16 INFO - https://queue.taskcluster.net/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 19:26:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 19:26:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 19:26:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 19:26:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 19:26:43 INFO - Downloaded 1199 bytes. 19:26:43 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 19:26:43 INFO - Using the following test package requirements: 19:26:43 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 19:26:43 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:26:43 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 19:26:43 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:26:43 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 19:26:43 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:26:43 INFO - u'jsshell-mac.zip'], 19:26:43 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:26:43 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 19:26:43 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 19:26:43 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:26:43 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 19:26:43 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:26:43 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 19:26:43 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:26:43 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 19:26:43 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:26:43 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 19:26:43 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:26:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:26:43 INFO - https://queue.taskcluster.net/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:26:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:26:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:26:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:26:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:26:46 INFO - Downloaded 18697063 bytes. 19:26:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:26:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:26:49 INFO - Return code: 0 19:26:49 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:26:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:26:49 INFO - https://queue.taskcluster.net/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:26:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:26:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:26:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:26:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:26:50 INFO - Downloaded 18697063 bytes. 19:26:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:26:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:26:53 INFO - Return code: 0 19:26: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')]} 19:26:53 INFO - https://queue.taskcluster.net/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 19:26:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:26:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:26:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:26:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:26:54 INFO - Downloaded 10834063 bytes. 19:26:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:26:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 19:26:55 INFO - Return code: 0 19:26: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')]} 19:26:55 INFO - https://queue.taskcluster.net/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 19:26:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:26:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:26:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 19:26:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 19:26:59 INFO - Downloaded 111282794 bytes. 19:26:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:26:59 INFO - mkdir: /builds/slave/test/properties 19:26:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:26:59 INFO - Writing to file /builds/slave/test/properties/build_url 19:26:59 INFO - Contents: 19:26:59 INFO - build_url:https://queue.taskcluster.net/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:27:00 INFO - Running post-action listener: _resource_record_post_action 19:27:00 INFO - Running post-action listener: set_extra_try_arguments 19:27:00 INFO - ##### 19:27:00 INFO - ##### Finished download-and-extract step (success) 19:27:00 INFO - ##### 19:27:00 INFO - ##### 19:27:00 INFO - ##### Running populate-webroot step. 19:27:00 INFO - ##### 19:27:00 INFO - Running pre-action listener: _resource_record_pre_action 19:27:00 INFO - Running main action method: populate_webroot 19:27:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:27:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:27:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:27:00 INFO - '--tppagecycles', 19:27:00 INFO - '7'], 19:27:00 INFO - 'tests': ['tsvgm']}}, 19:27:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:27:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:27:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:27:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:27:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:27:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:27:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:27:00 INFO - 'talos_options': ['--disable-e10s'], 19:27:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:27:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:27:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:27:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:27:00 INFO - 'talos_options': ['--disable-e10s'], 19:27:00 INFO - 'tests': ['damp', 'tps']}, 19:27:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:27:00 INFO - 'tests': ['damp', 'tps']}, 19:27:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:27:00 INFO - 'tests': ['dromaeo_dom']}, 19:27:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:27:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:27:00 INFO - 'tests': ['a11yr', 19:27:00 INFO - 'ts_paint', 19:27:00 INFO - 'tpaint', 19:27:00 INFO - 'sessionrestore', 19:27:00 INFO - 'sessionrestore_no_auto_restore', 19:27:00 INFO - 'tabpaint']}, 19:27:00 INFO - 'other-e10s': {'tests': ['a11yr', 19:27:00 INFO - 'ts_paint', 19:27:00 INFO - 'tpaint', 19:27:00 INFO - 'sessionrestore', 19:27:00 INFO - 'sessionrestore_no_auto_restore', 19:27:00 INFO - 'tabpaint']}, 19:27:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:27:00 INFO - 'ts_paint', 19:27:00 INFO - 'tpaint', 19:27:00 INFO - 'sessionrestore', 19:27:00 INFO - 'sessionrestore_no_auto_restore', 19:27:00 INFO - 'tabpaint']}, 19:27:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:27:00 INFO - 'ts_paint', 19:27:00 INFO - 'tpaint', 19:27:00 INFO - 'sessionrestore', 19:27:00 INFO - 'sessionrestore_no_auto_restore', 19:27:00 INFO - 'tabpaint']}, 19:27:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:27:00 INFO - 'tests': ['a11yr', 19:27:00 INFO - 'ts_paint', 19:27:00 INFO - 'tpaint', 19:27:00 INFO - 'sessionrestore', 19:27:00 INFO - 'sessionrestore_no_auto_restore', 19:27:00 INFO - 'tabpaint']}, 19:27:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:27:00 INFO - 'tests': ['a11yr', 19:27:00 INFO - 'ts_paint', 19:27:00 INFO - 'tpaint', 19:27:00 INFO - 'sessionrestore', 19:27:00 INFO - 'sessionrestore_no_auto_restore', 19:27:00 INFO - 'tabpaint']}, 19:27:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:27:00 INFO - 'tests': ['tsvgx', 19:27:00 INFO - 'tsvgr_opacity', 19:27:00 INFO - 'tart', 19:27:00 INFO - 'tscrollx', 19:27:00 INFO - 'cart']}, 19:27:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:27:00 INFO - 'tsvgr_opacity', 19:27:00 INFO - 'tart', 19:27:00 INFO - 'tscrollx', 19:27:00 INFO - 'cart']}, 19:27:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:27:00 INFO - 'talos_options': ['--disable-e10s'], 19:27:00 INFO - 'tests': ['tp5o']}, 19:27:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:27:00 INFO - 'tests': ['tp5o']}, 19:27:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:27:00 INFO - 'talos_options': ['--disable-e10s', 19:27:00 INFO - '--xperf_path', 19:27:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:27:00 INFO - 'tests': ['tp5n']}, 19:27:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:27:00 INFO - 'talos_options': ['--xperf_path', 19:27:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:27:00 INFO - 'tests': ['tp5n']}}, 19:27:00 INFO - 'talos.zip': {'path': '', 19:27:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:27:00 INFO - Running post-action listener: _resource_record_post_action 19:27:00 INFO - ##### 19:27:00 INFO - ##### Finished populate-webroot step (success) 19:27:00 INFO - ##### 19:27:00 INFO - ##### 19:27:00 INFO - ##### Running create-virtualenv step. 19:27:00 INFO - ##### 19:27:00 INFO - Running pre-action listener: _resource_record_pre_action 19:27:00 INFO - Running main action method: create_virtualenv 19:27:00 INFO - Creating virtualenv /builds/slave/test/build/venv 19:27:00 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 19:27:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:27:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:27:01 INFO - Using real prefix '/tools/python27' 19:27:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:27:01 INFO - Installing distribute.............................................................................................................................................................................................done. 19:27:04 INFO - Installing pip.................done. 19:27:04 INFO - Return code: 0 19:27:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:27: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')]} 19:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27: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')]} 19:27:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10605da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106063570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88615694c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f01d30>, '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 0x105d25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ef8b20>, '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.xHCPsN9u09/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.aNgyg6Kdto/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 19:27: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', 'pip>=1.5'] in /builds/slave/test/build 19:27: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 pip>=1.5 19:27:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xHCPsN9u09/Render', 19:27:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:27:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:27:04 INFO - 'HOME': '/Users/cltbld', 19:27:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:27:04 INFO - 'LOGNAME': 'cltbld', 19:27:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:04 INFO - 'MOZ_NO_REMOTE': '1', 19:27:04 INFO - 'NO_EM_RESTART': '1', 19:27:04 INFO - 'PAGER': '/bin/cat', 19:27:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:27:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:04 INFO - 'PWD': '/builds/slave/test', 19:27:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:27:04 INFO - 'SHELL': '/bin/bash', 19:27:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aNgyg6Kdto/Listeners', 19:27:04 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 19:27:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:27:04 INFO - 'USER': 'cltbld', 19:27:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:27:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:27:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:04 INFO - 'XPC_FLAGS': '0x0', 19:27:04 INFO - 'XPC_SERVICE_NAME': '0', 19:27:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:27:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:04 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 19:27:04 INFO - Cleaning up... 19:27:04 INFO - Return code: 0 19:27:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:27: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')]} 19:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27: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')]} 19:27:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27: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', '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 0x10605da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106063570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88615694c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f01d30>, '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 0x105d25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ef8b20>, '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.xHCPsN9u09/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.aNgyg6Kdto/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 19:27: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', 'psutil>=3.1.1'] in /builds/slave/test/build 19:27: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 psutil>=3.1.1 19:27:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xHCPsN9u09/Render', 19:27:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:27:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:27:04 INFO - 'HOME': '/Users/cltbld', 19:27:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:27:04 INFO - 'LOGNAME': 'cltbld', 19:27:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:04 INFO - 'MOZ_NO_REMOTE': '1', 19:27:04 INFO - 'NO_EM_RESTART': '1', 19:27:04 INFO - 'PAGER': '/bin/cat', 19:27:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:27:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:04 INFO - 'PWD': '/builds/slave/test', 19:27:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:27:04 INFO - 'SHELL': '/bin/bash', 19:27:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aNgyg6Kdto/Listeners', 19:27:04 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 19:27:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:27:04 INFO - 'USER': 'cltbld', 19:27:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:27:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:27:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:04 INFO - 'XPC_FLAGS': '0x0', 19:27:04 INFO - 'XPC_SERVICE_NAME': '0', 19:27:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:27:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:05 INFO - Downloading/unpacking psutil>=3.1.1 19:27: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 19:27: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 19:27: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 19:27: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 19:27: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 19:27: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 19:27:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:27:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:27:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:27:08 INFO - Installing collected packages: psutil 19:27:08 INFO - Running setup.py install for psutil 19:27:08 INFO - building 'psutil._psutil_osx' extension 19:27:08 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 19:27:09 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 19:27:09 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 19:27:09 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 19:27:09 INFO - building 'psutil._psutil_posix' extension 19:27:09 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 19:27:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:27:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:27:09 INFO - ^ 19:27:09 INFO - 1 warning generated. 19:27:09 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 19:27:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:27:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:27:09 INFO - Successfully installed psutil 19:27:09 INFO - Cleaning up... 19:27:09 INFO - Return code: 0 19:27:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:27: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')]} 19:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27: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')]} 19:27:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:09 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 0x10605da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106063570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88615694c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f01d30>, '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 0x105d25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ef8b20>, '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.xHCPsN9u09/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.aNgyg6Kdto/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 19:27:09 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 19:27:09 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 19:27:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xHCPsN9u09/Render', 19:27:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:27:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:27:09 INFO - 'HOME': '/Users/cltbld', 19:27:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:27:09 INFO - 'LOGNAME': 'cltbld', 19:27:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:09 INFO - 'MOZ_NO_REMOTE': '1', 19:27:09 INFO - 'NO_EM_RESTART': '1', 19:27:09 INFO - 'PAGER': '/bin/cat', 19:27:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:27:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:09 INFO - 'PWD': '/builds/slave/test', 19:27:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:27:09 INFO - 'SHELL': '/bin/bash', 19:27:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aNgyg6Kdto/Listeners', 19:27:09 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 19:27:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:27:09 INFO - 'USER': 'cltbld', 19:27:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:27:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:27:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:09 INFO - 'XPC_FLAGS': '0x0', 19:27:09 INFO - 'XPC_SERVICE_NAME': '0', 19:27:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:27:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:27:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:27:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:27:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:27:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:27:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:27:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:27:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:27:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:27:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:27:12 INFO - Installing collected packages: mozsystemmonitor 19:27:12 INFO - Running setup.py install for mozsystemmonitor 19:27:12 INFO - Successfully installed mozsystemmonitor 19:27:12 INFO - Cleaning up... 19:27:12 INFO - Return code: 0 19:27:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:27:12 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')]} 19:27:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:12 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')]} 19:27:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:12 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 0x10605da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106063570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88615694c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f01d30>, '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 0x105d25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ef8b20>, '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.xHCPsN9u09/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.aNgyg6Kdto/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 19:27:12 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 19:27:12 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 19:27:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xHCPsN9u09/Render', 19:27:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:27:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:27:12 INFO - 'HOME': '/Users/cltbld', 19:27:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:27:12 INFO - 'LOGNAME': 'cltbld', 19:27:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:12 INFO - 'MOZ_NO_REMOTE': '1', 19:27:12 INFO - 'NO_EM_RESTART': '1', 19:27:12 INFO - 'PAGER': '/bin/cat', 19:27:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:27:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:12 INFO - 'PWD': '/builds/slave/test', 19:27:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:27:12 INFO - 'SHELL': '/bin/bash', 19:27:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aNgyg6Kdto/Listeners', 19:27:12 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 19:27:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:27:12 INFO - 'USER': 'cltbld', 19:27:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:27:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:27:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:12 INFO - 'XPC_FLAGS': '0x0', 19:27:12 INFO - 'XPC_SERVICE_NAME': '0', 19:27:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:27:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:13 INFO - Downloading/unpacking blobuploader==1.2.4 19:27:13 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 19:27:13 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 19:27:13 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 19:27:13 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 19:27:13 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 19:27:13 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 19:27:15 INFO - Downloading blobuploader-1.2.4.tar.gz 19:27:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:27:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:27:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:27:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:27:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:27:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:27:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:27:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:27:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:27:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:27: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 19:27: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 19:27: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 19:27: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 19:27: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 19:27: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 19:27:16 INFO - Downloading docopt-0.6.1.tar.gz 19:27:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:27:16 INFO - Installing collected packages: blobuploader, requests, docopt 19:27:16 INFO - Running setup.py install for blobuploader 19:27:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:27:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:27:16 INFO - Running setup.py install for requests 19:27:17 INFO - Running setup.py install for docopt 19:27:17 INFO - Successfully installed blobuploader requests docopt 19:27:17 INFO - Cleaning up... 19:27:17 INFO - Return code: 0 19:27:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:27: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')]} 19:27:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27: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')]} 19:27:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:17 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 0x10605da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106063570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88615694c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f01d30>, '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 0x105d25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ef8b20>, '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.xHCPsN9u09/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.aNgyg6Kdto/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 19:27:17 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 19:27:17 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 19:27:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xHCPsN9u09/Render', 19:27:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:27:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:27:17 INFO - 'HOME': '/Users/cltbld', 19:27:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:27:17 INFO - 'LOGNAME': 'cltbld', 19:27:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:17 INFO - 'MOZ_NO_REMOTE': '1', 19:27:17 INFO - 'NO_EM_RESTART': '1', 19:27:17 INFO - 'PAGER': '/bin/cat', 19:27:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:27:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:17 INFO - 'PWD': '/builds/slave/test', 19:27:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:27:17 INFO - 'SHELL': '/bin/bash', 19:27:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aNgyg6Kdto/Listeners', 19:27:17 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 19:27:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:27:17 INFO - 'USER': 'cltbld', 19:27:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:27:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:27:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:17 INFO - 'XPC_FLAGS': '0x0', 19:27:17 INFO - 'XPC_SERVICE_NAME': '0', 19:27:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:27:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:27:18 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-Uhkxp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:27:18 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-ER0kcd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:27:18 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-gpiYgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:27:18 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-TGPZfF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:27:18 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-IBd5PR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:27:18 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-4Ez_qJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:27:18 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-80LVLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:27:18 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-C6ZkoC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:27:19 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-hiFocy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:27:19 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-L1BySp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:27:19 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-jTPbB3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:27:19 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-T5n_DT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:27:19 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-mvb3l_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:27:19 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-VSZwrp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:27:19 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-Nn9XmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:27:19 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-tmu_Mn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:27:19 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-NW8Xf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:27:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:27:19 INFO - Running setup.py install for manifestparser 19:27:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:27:20 INFO - Running setup.py install for mozcrash 19:27:20 INFO - Running setup.py install for mozdebug 19:27:20 INFO - Running setup.py install for mozdevice 19:27:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:27:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:27:20 INFO - Running setup.py install for mozfile 19:27:20 INFO - Running setup.py install for mozhttpd 19:27:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:27:20 INFO - Running setup.py install for mozinfo 19:27:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:27:21 INFO - Running setup.py install for mozInstall 19:27:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:27:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:27:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:27:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:27:21 INFO - Running setup.py install for mozleak 19:27:21 INFO - Running setup.py install for mozlog 19:27:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:27:21 INFO - Running setup.py install for moznetwork 19:27:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:27:21 INFO - Running setup.py install for mozprocess 19:27:21 INFO - Running setup.py install for mozprofile 19:27:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:27:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:27:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:27:21 INFO - Running setup.py install for mozrunner 19:27:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:27:22 INFO - Running setup.py install for mozscreenshot 19:27:22 INFO - Running setup.py install for moztest 19:27:22 INFO - Running setup.py install for mozversion 19:27:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:27:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:27:22 INFO - Cleaning up... 19:27:22 INFO - Return code: 0 19:27:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:27:22 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')]} 19:27:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:22 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')]} 19:27:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:22 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 0x10605da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106063570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88615694c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f01d30>, '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 0x105d25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ef8b20>, '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.xHCPsN9u09/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.aNgyg6Kdto/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 19:27:22 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 19:27:22 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 19:27:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xHCPsN9u09/Render', 19:27:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:27:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:27:22 INFO - 'HOME': '/Users/cltbld', 19:27:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:27:22 INFO - 'LOGNAME': 'cltbld', 19:27:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:22 INFO - 'MOZ_NO_REMOTE': '1', 19:27:22 INFO - 'NO_EM_RESTART': '1', 19:27:22 INFO - 'PAGER': '/bin/cat', 19:27:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:27:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:22 INFO - 'PWD': '/builds/slave/test', 19:27:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:27:22 INFO - 'SHELL': '/bin/bash', 19:27:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aNgyg6Kdto/Listeners', 19:27:22 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 19:27:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:27:22 INFO - 'USER': 'cltbld', 19:27:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:27:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:27:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:22 INFO - 'XPC_FLAGS': '0x0', 19:27:22 INFO - 'XPC_SERVICE_NAME': '0', 19:27:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:27:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:27:22 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-2Ooedp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:27:22 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)) 19:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:27:22 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-U9syKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:27:23 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)) 19:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:27:23 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-1mk0L1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:27:23 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)) 19:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:27:23 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-JNz6ui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:27:23 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)) 19:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:27:23 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-qhciiH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:27:23 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)) 19:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:27:23 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-RWJR9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:27:23 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)) 19:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:27:23 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-jQ1LRp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:27:23 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)) 19:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:27:23 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-o1L06M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:27:23 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)) 19:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:27:23 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-_xWSBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:27:23 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)) 19:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:27:23 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-tviwKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:27:23 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)) 19:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:27:23 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-RDqWdW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:27:23 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)) 19:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:27:23 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-VBBWh2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:27:24 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)) 19:27:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:27:24 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-dNZJlH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:27:24 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)) 19:27:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:27:24 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-omI57d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:27:24 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)) 19:27:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:27:24 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-kSopHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:27:24 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)) 19:27:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:27:24 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-amiw4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:27:24 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)) 19:27:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:27:24 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-X3uQ6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:27:24 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)) 19:27:24 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)) 19:27:24 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)) 19:27:24 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)) 19:27:24 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)) 19:27:24 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)) 19:27:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:27:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:27:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:27:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:27:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:27:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:27:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:27:26 INFO - Downloading blessings-1.6.tar.gz 19:27:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:27:27 INFO - Installing collected packages: blessings 19:27:27 INFO - Running setup.py install for blessings 19:27:27 INFO - Successfully installed blessings 19:27:27 INFO - Cleaning up... 19:27:27 INFO - Return code: 0 19:27:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:27:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:27:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:27:27 INFO - Reading from file tmpfile_stdout 19:27:27 INFO - Current package versions: 19:27:27 INFO - blessings == 1.6 19:27:27 INFO - blobuploader == 1.2.4 19:27:27 INFO - docopt == 0.6.1 19:27:27 INFO - manifestparser == 1.1 19:27:27 INFO - mozInstall == 1.12 19:27:27 INFO - mozcrash == 0.17 19:27:27 INFO - mozdebug == 0.1 19:27:27 INFO - mozdevice == 0.48 19:27:27 INFO - mozfile == 1.2 19:27:27 INFO - mozhttpd == 0.7 19:27:27 INFO - mozinfo == 0.9 19:27:27 INFO - mozleak == 0.1 19:27:27 INFO - mozlog == 3.2 19:27:27 INFO - moznetwork == 0.27 19:27:27 INFO - mozprocess == 0.22 19:27:27 INFO - mozprofile == 0.28 19:27:27 INFO - mozrunner == 6.11 19:27:27 INFO - mozscreenshot == 0.1 19:27:27 INFO - mozsystemmonitor == 0.3 19:27:27 INFO - moztest == 0.7 19:27:27 INFO - mozversion == 1.4 19:27:27 INFO - psutil == 3.1.1 19:27:27 INFO - requests == 1.2.3 19:27:27 INFO - wsgiref == 0.1.2 19:27:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:27:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:27:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:27:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:27 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 0x10605da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106063570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88615694c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f01d30>, '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 0x105d25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ef8b20>, '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.xHCPsN9u09/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.aNgyg6Kdto/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 19:27:27 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 19:27:27 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 19:27:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xHCPsN9u09/Render', 19:27:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:27:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:27:27 INFO - 'HOME': '/Users/cltbld', 19:27:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:27:27 INFO - 'LOGNAME': 'cltbld', 19:27:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:27 INFO - 'MOZ_NO_REMOTE': '1', 19:27:27 INFO - 'NO_EM_RESTART': '1', 19:27:27 INFO - 'PAGER': '/bin/cat', 19:27:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:27:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:27 INFO - 'PWD': '/builds/slave/test', 19:27:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:27:27 INFO - 'SHELL': '/bin/bash', 19:27:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aNgyg6Kdto/Listeners', 19:27:27 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 19:27:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:27:27 INFO - 'USER': 'cltbld', 19:27:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:27:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:27:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:27 INFO - 'XPC_FLAGS': '0x0', 19:27:27 INFO - 'XPC_SERVICE_NAME': '0', 19:27:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:27:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:27 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)) 19:27:27 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)) 19:27:27 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)) 19:27:27 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)) 19:27:27 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)) 19:27:27 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)) 19:27:27 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)) 19:27:27 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)) 19:27:27 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)) 19:27:27 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)) 19:27:27 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)) 19:27:27 INFO - Cleaning up... 19:27:27 INFO - Return code: 0 19:27:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:27:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:27:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:27:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:27 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 0x10605da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106063570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88615694c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f01d30>, '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 0x105d25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ef8b20>, '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.xHCPsN9u09/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.aNgyg6Kdto/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 19:27:27 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 19:27:27 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 19:27:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xHCPsN9u09/Render', 19:27:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:27:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:27:27 INFO - 'HOME': '/Users/cltbld', 19:27:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:27:27 INFO - 'LOGNAME': 'cltbld', 19:27:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:27 INFO - 'MOZ_NO_REMOTE': '1', 19:27:27 INFO - 'NO_EM_RESTART': '1', 19:27:27 INFO - 'PAGER': '/bin/cat', 19:27:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:27:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:27 INFO - 'PWD': '/builds/slave/test', 19:27:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:27:27 INFO - 'SHELL': '/bin/bash', 19:27:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aNgyg6Kdto/Listeners', 19:27:27 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 19:27:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:27:27 INFO - 'USER': 'cltbld', 19:27:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:27:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:27:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:27 INFO - 'XPC_FLAGS': '0x0', 19:27:27 INFO - 'XPC_SERVICE_NAME': '0', 19:27:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:27:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:28 INFO - Downloading/unpacking jsonschema 19:27:28 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 19:27:28 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 19:27:28 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 19:27:28 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 19:27:28 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 19:27:28 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 19:27:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:27:30 INFO - Downloading/unpacking functools32 (from jsonschema) 19:27:30 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 19:27:30 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 19:27:30 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 19:27:30 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 19:27:30 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 19:27:30 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 19:27:30 INFO - Downloading functools32-3.2.3-2.tar.gz 19:27:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:27:31 INFO - warning: no files found matching '*.txt' 19:27:31 INFO - no previously-included directories found matching 'build' 19:27:31 INFO - no previously-included directories found matching 'dist' 19:27:31 INFO - no previously-included directories found matching '.git*' 19:27:31 INFO - Installing collected packages: jsonschema, functools32 19:27:31 INFO - Running setup.py install for functools32 19:27:31 INFO - warning: no files found matching '*.txt' 19:27:31 INFO - no previously-included directories found matching 'build' 19:27:31 INFO - no previously-included directories found matching 'dist' 19:27:31 INFO - no previously-included directories found matching '.git*' 19:27:31 INFO - Successfully installed jsonschema functools32 19:27:31 INFO - Cleaning up... 19:27:31 INFO - Return code: 0 19:27:31 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:27:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:27:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:27:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:31 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10605da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106063570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88615694c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f01d30>, '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 0x105d25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ef8b20>, '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.xHCPsN9u09/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.aNgyg6Kdto/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 19:27:31 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', 'flake8'] in /builds/slave/test/build 19:27:31 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 flake8 19:27:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xHCPsN9u09/Render', 19:27:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:27:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:27:31 INFO - 'HOME': '/Users/cltbld', 19:27:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:27:31 INFO - 'LOGNAME': 'cltbld', 19:27:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:31 INFO - 'MOZ_NO_REMOTE': '1', 19:27:31 INFO - 'NO_EM_RESTART': '1', 19:27:31 INFO - 'PAGER': '/bin/cat', 19:27:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:27:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:31 INFO - 'PWD': '/builds/slave/test', 19:27:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:27:31 INFO - 'SHELL': '/bin/bash', 19:27:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aNgyg6Kdto/Listeners', 19:27:31 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 19:27:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:27:31 INFO - 'USER': 'cltbld', 19:27:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:27:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:27:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:31 INFO - 'XPC_FLAGS': '0x0', 19:27:31 INFO - 'XPC_SERVICE_NAME': '0', 19:27:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:27:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:31 INFO - Downloading/unpacking flake8 19:27:31 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 19:27:31 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 19:27:31 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 19:27:31 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 19:27:31 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 19:27:31 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 19:27:34 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:27:35 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:27:35 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 19:27:35 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 19:27:35 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 19:27:35 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 19:27:35 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 19:27:35 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 19:27:35 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:27:35 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:27:35 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 19:27:35 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 19:27:35 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 19:27:35 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 19:27:35 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 19:27:35 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 19:27:35 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:27:35 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 19:27:35 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 19:27:35 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 19:27:35 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 19:27:35 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 19:27:35 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 19:27:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:27:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:27:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:27:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:27:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:27:36 INFO - no previously-included directories found matching 'docs/_build' 19:27:36 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:27:36 INFO - Running setup.py install for pep8 19:27:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:27:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:27:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:27:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:27:36 INFO - no previously-included directories found matching 'docs/_build' 19:27:36 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:27:36 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:27:36 INFO - Cleaning up... 19:27:36 INFO - Return code: 0 19:27:36 INFO - Running post-action listener: _resource_record_post_action 19:27:36 INFO - Running post-action listener: _start_resource_monitoring 19:27:36 INFO - Starting resource monitoring. 19:27:36 INFO - ##### 19:27:36 INFO - ##### Finished create-virtualenv step (success) 19:27:36 INFO - ##### 19:27:36 INFO - ##### 19:27:36 INFO - ##### Running install step. 19:27:36 INFO - ##### 19:27:36 INFO - Running pre-action listener: _resource_record_pre_action 19:27:36 INFO - Running main action method: install 19:27:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:27:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:27:36 INFO - Reading from file tmpfile_stdout 19:27:36 INFO - Detecting whether we're running mozinstall >=1.0... 19:27:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:27:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:27:37 INFO - Reading from file tmpfile_stdout 19:27:37 INFO - Output received: 19:27:37 INFO - Usage: mozinstall [options] installer 19:27:37 INFO - Options: 19:27:37 INFO - -h, --help show this help message and exit 19:27:37 INFO - -d DEST, --destination=DEST 19:27:37 INFO - Directory to install application into. [default: 19:27:37 INFO - "/builds/slave/test"] 19:27:37 INFO - --app=APP Application being installed. [default: firefox] 19:27:37 INFO - mkdir: /builds/slave/test/build/application 19:27:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:27:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:28:11 INFO - Reading from file tmpfile_stdout 19:28:11 INFO - Output received: 19:28:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:28:11 INFO - Running post-action listener: _resource_record_post_action 19:28:11 INFO - ##### 19:28:11 INFO - ##### Finished install step (success) 19:28:11 INFO - ##### 19:28:11 INFO - ##### 19:28:11 INFO - ##### Running run-tests step. 19:28:11 INFO - ##### 19:28:11 INFO - Running pre-action listener: _resource_record_pre_action 19:28:11 INFO - Running main action method: run_tests 19:28:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:28:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:28:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:28:11 INFO - 2016-06-01 19:28:11.110 screenresolution[1644:7995] starting screenresolution argv=screenresolution get 19:28:11 INFO - 2016-06-01 19:28:11.112 screenresolution[1644:7995] Display 0: 1600x1200x32@60 19:28:11 INFO - 2016-06-01 19:28:11.118 screenresolution[1645:7997] starting screenresolution argv=screenresolution list 19:28:11 INFO - Available Modes on Display 0 19:28:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:28:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:28:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:28:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:28:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:28:11 INFO - Intel Iris: 19:28:11 INFO - Chipset Model: Intel Iris 19:28:11 INFO - Type: GPU 19:28:11 INFO - Bus: Built-In 19:28:11 INFO - VRAM (Dynamic, Max): 1536 MB 19:28:11 INFO - Vendor: Intel (0x8086) 19:28:11 INFO - Device ID: 0x0a2e 19:28:11 INFO - Revision ID: 0x0009 19:28:11 INFO - Displays: 19:28:11 INFO - Display: 19:28:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:28:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:28:11 INFO - Display Serial Number: 1600x1200 60 19:28:11 INFO - Main Display: Yes 19:28:11 INFO - Mirror: Off 19:28:11 INFO - Online: Yes 19:28:11 INFO - Rotation: Supported 19:28:11 INFO - Return code: 0 19:28:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:28:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:28:11 INFO - Python 2.7.3 19:28:11 INFO - Return code: 0 19:28:11 INFO - grabbing minidump binary from tooltool 19:28:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:28:11 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f01d30>, '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 0x105d25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ef8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:28:11 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 19:28:11 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 19:28:11 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 19:28:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:28:11 INFO - Return code: 0 19:28:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:28:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:28:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:28:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:28:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:28:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:28:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:28:12 INFO - Return code: 0 19:28:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0033', '--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 19:28:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0033 --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 19:28:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xHCPsN9u09/Render', 19:28:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:28:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:28:12 INFO - 'HOME': '/Users/cltbld', 19:28:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:28:12 INFO - 'LOGNAME': 'cltbld', 19:28:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:28:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:28:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:28:12 INFO - 'MOZ_NO_REMOTE': '1', 19:28:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:28:12 INFO - 'NO_EM_RESTART': '1', 19:28:12 INFO - 'PAGER': '/bin/cat', 19:28:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:28:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:28:12 INFO - 'PWD': '/builds/slave/test', 19:28:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:28:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:28:12 INFO - 'SHELL': '/bin/bash', 19:28:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aNgyg6Kdto/Listeners', 19:28:12 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 19:28:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:28:12 INFO - 'USER': 'cltbld', 19:28:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:28:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:28:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:28:12 INFO - 'XPC_FLAGS': '0x0', 19:28:12 INFO - 'XPC_SERVICE_NAME': '0', 19:28:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:28:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0033', '--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 19:28:12 INFO - mozversion application_buildid: 20160601171646 19:28:12 INFO - mozversion application_changeset: 279597150b922a9b762de1dc88fbf083a31a4f6f 19:28:12 INFO - mozversion application_display_name: Nightly 19:28:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:28:12 INFO - mozversion application_name: Firefox 19:28:12 INFO - mozversion application_remotingname: firefox 19:28:12 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 19:28:12 INFO - mozversion application_vendor: Mozilla 19:28:12 INFO - mozversion application_version: 49.0a1 19:28:12 INFO - mozversion platform_buildid: 20160601171646 19:28:12 INFO - mozversion platform_changeset: 279597150b922a9b762de1dc88fbf083a31a4f6f 19:28:12 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 19:28:12 INFO - mozversion platform_version: 49.0a1 19:28:12 INFO - using testdate: 1464834492 19:28:12 INFO - actual date: 1464834492 19:28:12 INFO - starting webserver on 'localhost:49277' 19:28:12 INFO - SUITE-START | Running 2 tests 19:28:12 INFO - TEST-START | dromaeo_css 19:28:12 INFO - operating with platform_type : mac_ 19:28:12 INFO - Initialising browser for dromaeo_css test... 19:28:12 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpHCq6pd/profile http://localhost:49277/getInfo.html) 19:28:15 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 19:28:15 INFO - PROCESS | 1663 | colorDepth:24 19:28:15 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 19:28:15 INFO - PROCESS | 1663 | __metrics 19:28:15 INFO - PROCESS | 1663 | 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" 19:28:15 ERROR - PROCESS | 1663 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:28:16 INFO - TEST-INFO | 1663: exit 0 19:28:16 INFO - Browser initialized. 19:28:16 INFO - Running cycle 1/1 for dromaeo_css test... 19:28:16 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpHCq6pd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:28:27 INFO - PROCESS | 1666 | RSS: Main: 241983488 19:28:27 INFO - PROCESS | 1666 | 19:29:19 INFO - PROCESS | 1666 | 1464834559445 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] 19:31:29 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 19:31:31 INFO - PROCESS | 1666 | RSS: Main: 350347264 19:31:31 INFO - PROCESS | 1666 | 19:34:33 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 19:34:34 INFO - PROCESS | 1666 | RSS: Main: 381284352 19:34:34 INFO - PROCESS | 1666 | 19:37:36 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 19:37:37 INFO - PROCESS | 1666 | RSS: Main: 374009856 19:37:37 INFO - PROCESS | 1666 | 19:40:39 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 19:40:40 INFO - PROCESS | 1666 | RSS: Main: 371326976 19:40:40 INFO - PROCESS | 1666 | 19:43:43 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 19:43:44 INFO - PROCESS | 1666 | RSS: Main: 338690048 19:43:44 INFO - PROCESS | 1666 | 19:46:47 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 19:46:47 INFO - PROCESS | 1666 | RSS: Main: 379056128 19:46:47 INFO - PROCESS | 1666 | 19:46:47 INFO - PROCESS | 1666 | __start_tp_report 19:46:47 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 19:46:47 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 19:46:47 INFO - PROCESS | 1666 | |i|pagename|runs| 19:46:47 INFO - PROCESS | 1666 | |0;dojo.html;3466.2547552276264;3475.1138459692775;3481.2689335239606;3491.0748651607314;3540.309639620275;4646.233371493704;5665.093585026397;5756.654600723959;5783.710814459276;5803.622764520309;5598.1602759586085;5686.118651609007;5703.973284808732;5747.310322761275;5805.100209467529;5156.458571849958;5178.637495375326;5248.317718696583;5261.368635763694;5368.597355198356;5340.49265319794;5351.57187425007;5386.084365657828;5393.838184854441;5439.102548079562;5168.689878607277;5261.15821468567;5265.236540701577;5296.443873393277;5324.148136298174;5195.480451954812;5221.686698798084;5249.212618107276;5255.264263003182;5255.60582956276;5249.081410753103;5282.867928301823;5292.364916210041;5316.946830531684;5358.276632654581;4763.14263432582;5604.719764011824;5656.802011929604;5697.743601537941;5752.654840709571;6212.844678883064;6217.502599792006;6220.688965551704;6223.875522489525;6228.096925945758;6205.131281620576;6230.2523697156175;6242.812715618538;6253.656048917308;6259.0924315973925;4851.611871050308;5891.970540147315;5936.13925980739;5939.910901336483;5959.507701525309;6289.7484100636375;6298.779542715983;6299.7795077173;6301.0233413820015;6306.2747784004505;6219.222597175353;6236.937630623726;6249.875002499924;6254.499640028786;6265.342139075331;6110.29731580865;6153.723082461211;6203.131561581382;6219.346968568235;6290.528210384239;6139.570230083851;6203.348648391944;6208.627482350983;6209.782657607052;6216.689165541705;6117.827404800828;6196.47329976957;6211.937880621226;6224.564280500322;6232.937670623326;4126.938095928564;4239.236937351245;4971.304017437562;5014.498550145029;5017.9247311290355;3810.580836108026;4439.844605438794;4501.144782491382;4521.321801729767;4539.228331183706;4971.6576524338225;5662.320521537442;5809.448102430347;5813.79651712188;5864.06175011996;6123.282621474291;6199.256089269226;6232.8130156095385;6266.530010249249;6283.963146080937;5680.176374425685;5734.426557344233;5801.796937107265;5806.941930580725;5872;4623.491415944243;5434.10337294342;5459.235707001023;5470.8085217017215;5499.395066542677;6025.855087533355;6346.777862774781;6360.268569114519;6361.522885783585;6374.298827129012;5547.389787123332;6160.137580738789;6180.536459765445;6262.906056409066;6345.206849143857;5591.580631452576;6290.150829637941;6343.333949935375;6365.236171659245;6369.33122022181;6213.00591905293;6284.371562843679;6296.5277604178955;6312.5581209314905;6321.146645202839;6096.829073522206;6113.57204995646;6117.096718007379;6135.049067394382;6148.815535533941;6097.878042439215;6139.055828126434;6147.108669242845;6159.199304090619;6206.844828879133;6169.043798211284;6200.3179650237535;6226.750929962751;6241.31345551998;6241.8751624966335;5924.229850119457;6032.758689652364;6068.939310607014;6089.056196289582;6133.539984501091;6210.689465526615;6211.81364559064;6216.751329946752;6217.4715149212825;6217.720202590804;5899.91150132757;6040.465739638176;6066.423689749467;6092.268927728701;6107.552954153702 19:46:47 INFO - PROCESS | 1666 | |1;ext.html;499.0194268262863;513.9948600513995;522.5323335614625;553.4714347797853;574.9195112684222;24469.877650611743;43310;44416.55583444165;44525;46625.53374466254;37421.81289093557;45377.773111134484;45748.085038299214;45809.77095114528;45871;9543.856842147356;9777.46223957682;9788.657396991106;9796.657117000907;9808.803823923517;9611;9766.706998790047;9786.412815231073;9789.265805064613;9797.706068817946;9758.658446954358;9786.559604817765;9797.608095676165;9813.607455701764;9826.557804898797;9567.37812042215;9579.425234485883;9585.568649410723;9592.952035239849;9601.183899368598;9581.520923953774;9704.223662107015;9710.90289097107;9725;9730.12428881404;41272.58727412747;41697;41777.373339399936;41796.58203417957;41932.37101443481;3442;3498.0904964709102;3580.5882323532865;3641.7268704847243;3867.7292589518743;31014.84492577545;31642.525362119588;31715.20711982196;32212;32829;3075.5540446635337;3116.5792617996735;3151.3854798314337;3171.0645359618966;3234.8867789627484;487029;488111;488735;488920;489839;61579;62965.37034629595;62976.37023629797;63045.05432418518;63127;35222.471662925076;35259.4711079334;36170.09574760652;37773.81113094417;37800.62199378026;16096.275667594986;16243.431479898383;16272.918635406748;16302.592435189215;16339.18304084791;12279.447424865903;12358.134930554954;12367.93816030932;12369.690757730948;12379.257244565355;20075.39773806759;20183.697244541345;20193.192272308945;20333;20352.491187720425;7801.29788319054;7805.609719514002;7842.568658723825;7859.685612575548;7877.921220787834;915.2174890468686;1013.8884722680499;1040.3913710479303;1069.6256310291335;1081.4430568257353;30834.69165308363;31025;31092;31532.842335788173;31790.523142152888;1064.4677661169414;1096.4079397125463;1121.5401685308984;1123.713453069462;1138.2146319039837;12256.571020014257;15869;15915.522534323989;16060.036397816168;16564.420245291356;714941;751940;759482.4051759522;759726;1025599;58424;59134;59538.70230648906;59639.40360596339;59998.40001600015;1301.2517802263546;1353.2962859312904;1421.8862491000696;1449.5506393018197;1468.9779653305209;25911.87044064843;29798.404031918806;30866.53700194454;32346.029619111465;33159;57785.71107144438;57926.420735792104;58659.70670146622;58666.4133358661;58721.412785871595;571298;573097;573191;581824;584107;22051;24431.14490992807;26537;26828.59757103684;27631.032913847917;48525;48606;49288.26067608917;49577;49770.75114624404;38393.61606383901;39734;40076.39885401663;40560.98597535085;40758;38382.040448989;38934;39101.60898391093;39659.00852478711;39776;37862.62137378701;38713.2257354857;38845.14435695538;39042.609573903894;39149.412758808074;32143.678563215002;34518.65481345255;34572.48141277933;34809.47785783165;34988.65011349854;23235.3029409118;24994;25530.617040744775;25812;25834.095806646677 19:46:47 INFO - PROCESS | 1666 | |2;jquery.html;323.2081400568607;389.6882494004797;390.7499200511671;392.70547089682753;405.5599674353326;19475.805241947575;20202.69695954558;21841.235556755517;21880.781192188075;21882.343529694095;11630;11876.28742275464;12180.756384872297;12191.51233950641;12220.755584888297;5184.766685499152;5355.464453554652;5416.214648875911;5419.83740487786;5421.268128802601;3841.61583841615;4062.512498500184;5660.094384898417;5976.521878249751;5980.671063091529;5903.527717782567;6040.093985902128;6041.788537401192;6046.516278697693;6051.576389652726;5832.445917637818;5967.850803729898;5972.611780234292;6014.097885317194;6017.1275165100815;3709.55485341758;5380.488853558905;5731.541476681856;5743.994800909866;5777.855553611152;13482.39329230187;13615;16130.677386452322;26043.869780651166;26071.56606386648;2007.6687346587796;2133.232036466871;2168.750593681731;2249.5950728868797;2329.9631663909568;10084.243681723827;10217.386956782542;10498.685039448828;11172.720681982933;15981.760273595906;1869.5513076861591;1878.9906050469797;1927.1424216223786;1940.6312800567846;1965.9705104423444;115745;119439;123820;129327.35336323352;132410.33794830964;11440.771184576428;11608.30350178992;11652.785970665547;11667.474963626657;11769;14608.415663373346;14686.41254349814;14691.55925322242;14714.484993025193;14715.484958026418;8273;8313.75763452242;8316.209960053786;8349.4155409122;8464.44981076228;8076.676932922617;8091.959540202261;8174.427790054758;8190.959045204736;8205.71280005197;4524.954750452519;4572.95427045732;4586.220342541776;4626.814927402934;4649.3026046093355;1639.7704321395015;1759.3992853633192;1792.3099606651533;1839.0069362544343;1980.1188471130497;821.482466046387;890.8708237305684;943.4716558727134;948.8671585977969;1010.7119470950744;20245.696314555295;20940.685889711665;21028;21088.17374017884;21367.78632213689;843.8017065989487;977.6236149082655;994.8905620381752;995.1590905684685;1031.7523794289316;10978.725531861768;11132.202985549222;11160;11242.66272011841;11248.831267530994;576526;585179;591574;597096;604041;81447.59276203699;81718;114621;130558;140168.29915850356;961.0293603460556;970.5341436110706;981.651513712636;982;987.2003677021636;18313.992730399423;18359.80661257014;18479.81520184781;18530.629387412446;18820.905895470434;51411;51734.74132629313;52863;54937;55707;72726;78178.6091069541;78202;78469.31193342205;80271.19728802638;8241;8267.917320826955;8298.543580103153;8396.328293736364;8479.36404769657;11968.640940771442;12812.103152779217;13128.934355328161;13184.274864882524;13257.535986240435;10471.267011309135;11027.83458248111;11136.554537818396;11480.483378248166;11495.597654082067;11280.774384512428;11308.773824523629;11363.54545818191;11572.421378931189;11598;9569.234461243223;11012.008919197311;11435.485403157045;11900.76198476043;11927.4036298183;8019.077806052145;8144.470609410369;8172.305354044735;8188.713395031145;8203.794905127419;10944.671659850217;11004.174686898354;11053.668389948314;11078.612248571262;11099 19:46:47 INFO - PROCESS | 1666 | |3;mootools.html;1404.3539971613056;1548.8218854831698;1562.1954693332934;1792.9152862518176;1950.5318723506355;7653.158152603216;8387.664493420256;8596.699115530966;8596.8710469343;8755.95622021889;8392.412531122824;8617.956910215456;8620.655173793057;8632.136786321365;8633.611487483062;2082.8125468707813;2152.5587254612765;2156.5363446858923;2341.063574570177;2372.074890792592;2180.367693368929;2195.5828392605436;2308.595995700746;2398.4603464220586;2402.7356990731005;2330.883455827202;2377.108584280895;2377.6552399902002;2382.29722231942;2422.4912768318673;2021.161218094489;2308.157522504282;2372.7864492195786;2376.2277259890607;2379.3694670912223;2108.8629239099564;2189.27753841232;2264.750877403484;2346.3312955807683;2356.4108972756812;7760.495567788075;8487.193716596914;8488.830223395498;8497.95751021247;8521.40350175488;5109.489051094898;5242.528172464458;5243.449437809013;5245.711485868275;5255.76349064293;3427.9314413711836;3537.1510837399096;3606.5311509503595;3611.7291203159607;3624.220792529619;1040.2926010312983;1069.4064794039305;1085.484394912418;1095.0856035210609;1096.6490722968574;11049.723756906062;11243.437828108643;11257.211995160342;11278.323300601991;11301.49143288554;4552.157850797613;4696.671233013656;4740.265258884879;4760.595349395271;4767.261074533453;9813.656522021838;9867.309288349747;9868.901310986941;9870.901290986998;9875.654352097643;5232.686038837682;5249.790008399697;5258.631895767336;5262.92105618416;5264.552513036435;4298.312270036779;4687.5781179693995;4708.105459962665;4735.59747421473;4795.328653988445;3661.3226553087766;3851.7111216658864;3959.6832253419657;3979;3984.900377490586;1264.6016504800957;1298.2275546050084;1299.883010529057;1308.129374993749;1310.298990040343;841.2722994609619;944.7213072143734;946.5740416812462;965.0840348662653;1003.1874181912674;5807.622504537276;5837.416258374129;5895.4399332063385;5905.232319798484;5998.910016349758;851.9095557686171;986.2800155886243;996.5963784667222;1003.2826529031777;1036.6734478639355;3497.877574284939;4028.133951200506;4028.133951200506;4075;4222.78886055696;10165.4917254137;10221;10339.276250662382;10364.274500785023;10378.844317335246;10215.795684086277;10286.485675716185;10295.433751143759;10310.226732995055;10358;7797.181295963956;7841.882371764314;7996.6401511930935;7999.3200577950365;8090.716824910982;5154.226865970135;5915.526757859447;6071.208993346885;6086.847828804138;6106.2061931948565;9371.250299975849;9374.95312523433;9814.75463113428;9826.213902887896;9846.458444785605;9631.325807193709;9956.352837065857;9975.60097596088;9985.800283994426;9989.250806189419;6825.453963682794;7153.320434558708;7505.249475052669;7512;7517.135529414188;7643.5796031218815;7943.046834379911;8193.09875913661;8199.71301004462;8322.833543329221;7187.209406965334;7278.417726581966;7280.453965952469;7389.076365454318;7441.962790186014;6563.146790917151;6896;7143.357097861218;7207.1711753146965;7253.709851605877;6541.280459149394;6794.694238759366;6816.48876334267;6832.248452670302;6886.208086069965;4943.209086546135;4945.357103576656;4980.402351717817;4992.350994370854;5084.313617661568;4294.420253265769;4995.900081998267;5000.274960130688;5064.7467626619255;5068.898622027613 19:46:47 INFO - PROCESS | 1666 | |4;prototype.html;301.28444943259757;306.4835751758287;317.370020509289;320.1963072687556;324.2121644404099;4653.511381304957;4810.470848206694;5310.309659744233;5329.253904453375;5339.065663508885;4213.494380674316;4665.76671166442;5258.421573626898;5322.270262164802;5331.654701418342;3715.9071023224365;4017.3572228443454;4046.9190616187657;4063.1061166543313;4076.8980775480554;3327.633960264369;3344.3812894614452;3693.4829123922673;3996.5429903133727;4071.5928407159345;3375.9324813503717;3454.842627719717;3632.7648599476174;3874.682607913309;4078.877633670995;3264.955214331415;3419.5296022710227;3805.219929914361;3943.467631869704;3979.582143874895;3130.9217269568217;3386.102682789063;3538.3630946429635;3539.557555305587;3557.786532808014;4431.028192454359;4607.585317321424;4948.38145231846;5070.048031507859;5129.9230511542355;3857.9228415431535;4160.479940007499;4564.968588977906;4567.099891568844;4605.861824145281;4783.425988881322;5460.645058071213;5510.889782204374;5519.724013799334;5563.972180139073;3662.468942003421;3862.3820188769664;3872;3872.1481274119647;3924.4309575111447;4954.380702412199;5202.621305354122;5232.529072383504;5802.854928626776;5822.592418530747;3924.843006279774;3945.7373640434785;3950.762954222756;3961.861334853324;4005.319095753671;3578.177019285579;3579.83890724918;4020.1758639478835;4027.3958906163725;4035.0517628357898;3707.758995665327;4483.103379324083;4508.909821803545;4509.278515437514;4529.954700452953;4780.4741478437345;5403.729813509309;5529.811090615456;5534.378793707219;5582.525485333792;1116.6873275482878;1261.3882267100448;1285.190330092036;1304.6757541095794;1312.7899536074374;701.235653138082;804.7022601637432;806.1293802693077;811.5333683132222;826.9786813285588;4015.4980627421573;4086.3870419436726;4147.709660323749;4342.522322544518;4379.4525684289465;4246.787660616957;4618.330342100444;4694.920168361296;4737.768180273085;4792.760361981887;4027.395890616431;4740.530435564917;4997.7251251180805;5085.440601533828;5241.453771137523;2532.2150133458686;2733.139061195717;2762.9171124866284;3042.1938186380626;3048.00355766973;5648.604597678205;5916.101110455846;5934.406559344031;5974.790882319098;6022.427869352405;5121.8463446096675;5922.96348139074;5946.502468963046;5979.4618484336615;5980.312264089598;5453.590980676465;5520.006398848246;5671.829845104653;6036.396360364016;6062.636241825505;4479.50725420197;5092.264194299337;5509.531689806251;5661.915071273852;5756.30924289088;5314.3596636304255;5660.830175094754;5687.061634830372;5688.459596338341;5697.373288938131;5535.868690554085;5900.70496475166;5945.702714864153;6003.849903752441;6039.818805435844;3828.4448754930936;3847.9230415392094;3860.3437415638846;3873.8256778445602;3936.3308237599103;4966.932109596454;5099.643024988215;5870.207521984648;5870.236869206976;5904;5129.666571672829;5398.598882703872;5642.266505354278;5717.771289148387;5739.282589676291;4930.260460930889;5407.161889907071;5610.298712660918;5639.436056394492;5647.576431767552;5084.618653601069;5243.921341179962;5277.514113003916;5395.973020134874;5422.213779001943;4768.546988036131;5364.7049412282695;5525.392206857162;5597;5636.830895073154;4496.7301961882395;4683.929741053955;5330.493603107699;5520.951019306319;5630.18362337467 19:46:47 INFO - PROCESS | 1666 | |5;yui.html;788.7909703928457;806.3226889412894;810.197904074966;845.6744153500904;875.2254254984339;15302.770458443118;15620.609484762857;15978.600534986632;15999.280032398541;15999.680006399894;14408.495702650409;14465.566033019;14586.051906626062;14603.78094328586;14605.926970365133;730.4777084384664;753.7625647920914;904.8145130248316;910.4491782471575;910.653951498433;899.491787140267;926.8285367207088;929.4794914847707;931.6133804471136;936.1060187520935;965.3821554205314;975.4683697384907;975.6633961283381;976.8485884687884;977.0522593084696;757.322196634015;778.7002004228357;782.9586649755811;963.7831487915233;964.310517979641;564.8277275430992;700.8072779985494;716.7634680555404;717.5981450387748;719.0867598150372;15050.849491505054;15063.774043389358;15088.924555377263;15093.849061509463;15099.320530576151;13962.720745585133;14108;14110.223937683424;14228.217448040354;14330.64173395663;10536.68389948295;12689.23864568129;12784.61646150617;12876.356182190853;13005.609831705064;4573.634109271284;4611.723296602215;4654.534546545352;4771.71369717818;4787.952120478785;98751.01248987562;99616.50191748995;100651;102200.4669929959;103625;9455.763605909908;9482.715518534455;9535;9681.757956051155;9808.950955245178;19031.809681903003;19057.428277151706;19071;19106.33127840519;19178.80821191798;7934.563599001995;8007.959960200162;8027.03675558925;8041.115477297492;8111.716089936824;4364.476262848479;4392.4289842320295;4394.846180383671;4397.714148580332;4402.762400766556;3416.5216869637775;3524.6475352465063;3529.5588051493564;3532.788032718045;3533.0107569880474;582.3419535924463;608.8782243551308;644.6261168522246;660.6267458885715;666.4102269491437;583.8744669895908;592.7984485275027;644.9645269510128;653.8496145886472;685.475611157465;8958.596863141174;9344.532773361305;9353.158215760614;9374.250059995184;9389.10803473669;551.2980337036817;608.6774009774826;619.8667286533417;650.3561474140566;656.8883289840738;4372;4418.1163767246035;4423;4423.181711383404;4563.018950925567;91785;94614;100556.49165262526;106813;139747;99942.50028749956;110062;118164.81835181538;123523.76476235302;126454;101501;106704.46647766711;107350.92649073566;108071.29821754519;111349;8360.70737524208;8432.36757243197;8586.441881277697;8606.26846718036;8865.246454051477;53687.73156134194;55478;55508.444915550324;56362;59655.70172149112;73743;84945;86141.56929215314;87601;88191;11446.37044962535;11613.941930290632;11620.244684095507;11623.76752464963;11815.350155741517;12540.749185016432;12631.242125472501;12743.745125097632;12881.227126372447;13351.599452016455;11748.485647874017;11825.113116516124;11998.100142489522;12243.571474998333;12320.260784352968;11766.882331176921;11859.644210673694;11870.703232419259;12297.378976866776;12368.505259789508;11650.941745291218;11890.227135236182;11920.523179073087;12070.939645301718;12212.414123661898;8847.894013248344;9899.359651603016;9962.203023758228;10228.846567301347;10405.375677459377;9660;9969.152622027208;10204.18366530661;10208.081272685196;10217.233707472118 19:46:47 INFO - PROCESS | 1666 | __end_tp_report 19:46:47 INFO - PROCESS | 1666 | __start_cc_report 19:46:47 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,1150 19:46:47 INFO - PROCESS | 1666 | __end_cc_report 19:46:47 INFO - PROCESS | 1666 | __startTimestamp1464835607636__endTimestamp 19:46:47 INFO - PROCESS | 1666 | 19:46:47 INFO - PROCESS | 1666 | ------- Summary: start ------- 19:46:47 INFO - PROCESS | 1666 | Number of tests: 6 19:46:47 INFO - PROCESS | 1666 | 19:46:47 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5744.78 Median:6066.42 stddev:664.26 (10.9%) stddev-sans-first:641.80 19:46:47 INFO - PROCESS | 1666 | Values: 3466.3 3475.1 3481.3 3491.1 3540.3 4646.2 5665.1 5756.7 5783.7 5803.6 5598.2 5686.1 5704.0 5747.3 5805.1 5156.5 5178.6 5248.3 5261.4 5368.6 5340.5 5351.6 5386.1 5393.8 5439.1 5168.7 5261.2 5265.2 5296.4 5324.1 5195.5 5221.7 5249.2 5255.3 5255.6 5249.1 5282.9 5292.4 5316.9 5358.3 4763.1 5604.7 5656.8 5697.7 5752.7 6212.8 6217.5 6220.7 6223.9 6228.1 6205.1 6230.3 6242.8 6253.7 6259.1 4851.6 5892.0 5936.1 5939.9 5959.5 6289.7 6298.8 6299.8 6301.0 6306.3 6219.2 6236.9 6249.9 6254.5 6265.3 6110.3 6153.7 6203.1 6219.3 6290.5 6139.6 6203.3 6208.6 6209.8 6216.7 6117.8 6196.5 6211.9 6224.6 6232.9 4126.9 4239.2 4971.3 5014.5 5017.9 3810.6 4439.8 4501.1 4521.3 4539.2 4971.7 5662.3 5809.4 5813.8 5864.1 6123.3 6199.3 6232.8 6266.5 6284.0 5680.2 5734.4 5801.8 5806.9 5872.0 4623.5 5434.1 5459.2 5470.8 5499.4 6025.9 6346.8 6360.3 6361.5 6374.3 5547.4 6160.1 6180.5 6262.9 6345.2 5591.6 6290.2 6343.3 6365.2 6369.3 6213.0 6284.4 6296.5 6312.6 6321.1 6096.8 6113.6 6117.1 6135.0 6148.8 6097.9 6139.1 6147.1 6159.2 6206.8 6169.0 6200.3 6226.8 6241.3 6241.9 5924.2 6032.8 6068.9 6089.1 6133.5 6210.7 6211.8 6216.8 6217.5 6217.7 5899.9 6040.5 6066.4 6092.3 6107.6 19:46:47 INFO - PROCESS | 1666 | 19:46:47 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:74653.77 Median:39404.21 stddev:171972.76 (436.4%) stddev-sans-first:172365.06 19:46:47 INFO - PROCESS | 1666 | Values: 499.0 514.0 522.5 553.5 574.9 24469.9 43310.0 44416.6 44525.0 46625.5 37421.8 45377.8 45748.1 45809.8 45871.0 9543.9 9777.5 9788.7 9796.7 9808.8 9611.0 9766.7 9786.4 9789.3 9797.7 9758.7 9786.6 9797.6 9813.6 9826.6 9567.4 9579.4 9585.6 9593.0 9601.2 9581.5 9704.2 9710.9 9725.0 9730.1 41272.6 41697.0 41777.4 41796.6 41932.4 3442.0 3498.1 3580.6 3641.7 3867.7 31014.8 31642.5 31715.2 32212.0 32829.0 3075.6 3116.6 3151.4 3171.1 3234.9 487029.0 488111.0 488735.0 488920.0 489839.0 61579.0 62965.4 62976.4 63045.1 63127.0 35222.5 35259.5 36170.1 37773.8 37800.6 16096.3 16243.4 16272.9 16302.6 16339.2 12279.4 12358.1 12367.9 12369.7 12379.3 20075.4 20183.7 20193.2 20333.0 20352.5 7801.3 7805.6 7842.6 7859.7 7877.9 915.2 1013.9 1040.4 1069.6 1081.4 30834.7 31025.0 31092.0 31532.8 31790.5 1064.5 1096.4 1121.5 1123.7 1138.2 12256.6 15869.0 15915.5 16060.0 16564.4 714941.0 751940.0 759482.4 759726.0 1025599.0 58424.0 59134.0 59538.7 59639.4 59998.4 1301.3 1353.3 1421.9 1449.6 1469.0 25911.9 29798.4 30866.5 32346.0 33159.0 57785.7 57926.4 58659.7 58666.4 58721.4 571298.0 573097.0 573191.0 581824.0 584107.0 22051.0 24431.1 26537.0 26828.6 27631.0 48525.0 48606.0 49288.3 49577.0 49770.8 38393.6 39734.0 40076.4 40561.0 40758.0 38382.0 38934.0 39101.6 39659.0 39776.0 37862.6 38713.2 38845.1 39042.6 39149.4 32143.7 34518.7 34572.5 34809.5 34988.7 23235.3 24994.0 25530.6 25812.0 25834.1 19:46:47 INFO - PROCESS | 1666 | 19:46:47 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:34119.77 Median:2289.78 stddev:98562.99 (4304.5%) stddev-sans-first:98806.82 19:46:47 INFO - PROCESS | 1666 | Values: 323.2 389.7 390.7 392.7 405.6 19475.8 20202.7 21841.2 21880.8 21882.3 11630.0 11876.3 12180.8 12191.5 12220.8 5184.8 5355.5 5416.2 5419.8 5421.3 3841.6 4062.5 5660.1 5976.5 5980.7 5903.5 6040.1 6041.8 6046.5 6051.6 5832.4 5967.9 5972.6 6014.1 6017.1 3709.6 5380.5 5731.5 5744.0 5777.9 13482.4 13615.0 16130.7 26043.9 26071.6 2007.7 2133.2 2168.8 2249.6 2330.0 10084.2 10217.4 10498.7 11172.7 15981.8 1869.6 1879.0 1927.1 1940.6 1966.0 115745.0 119439.0 123820.0 129327.4 132410.3 11440.8 11608.3 11652.8 11667.5 11769.0 14608.4 14686.4 14691.6 14714.5 14715.5 8273.0 8313.8 8316.2 8349.4 8464.4 8076.7 8092.0 8174.4 8191.0 8205.7 4525.0 4573.0 4586.2 4626.8 4649.3 1639.8 1759.4 1792.3 1839.0 1980.1 821.5 890.9 943.5 948.9 1010.7 20245.7 20940.7 21028.0 21088.2 21367.8 843.8 977.6 994.9 995.2 1031.8 10978.7 11132.2 11160.0 11242.7 11248.8 576526.0 585179.0 591574.0 597096.0 604041.0 81447.6 81718.0 114621.0 130558.0 140168.3 961.0 970.5 981.7 982.0 987.2 18314.0 18359.8 18479.8 18530.6 18820.9 51411.0 51734.7 52863.0 54937.0 55707.0 72726.0 78178.6 78202.0 78469.3 80271.2 8241.0 8267.9 8298.5 8396.3 8479.4 11968.6 12812.1 13128.9 13184.3 13257.5 10471.3 11027.8 11136.6 11480.5 11495.6 11280.8 11308.8 11363.5 11572.4 11598.0 9569.2 11012.0 11435.5 11900.8 11927.4 8019.1 8144.5 8172.3 8188.7 8203.8 10944.7 11004.2 11053.7 11078.6 11099.0 19:46:47 INFO - PROCESS | 1666 | 19:46:47 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5589.38 Median:5066.82 stddev:3082.34 (60.8%) stddev-sans-first:3074.94 19:46:47 INFO - PROCESS | 1666 | Values: 1404.4 1548.8 1562.2 1792.9 1950.5 7653.2 8387.7 8596.7 8596.9 8756.0 8392.4 8618.0 8620.7 8632.1 8633.6 2082.8 2152.6 2156.5 2341.1 2372.1 2180.4 2195.6 2308.6 2398.5 2402.7 2330.9 2377.1 2377.7 2382.3 2422.5 2021.2 2308.2 2372.8 2376.2 2379.4 2108.9 2189.3 2264.8 2346.3 2356.4 7760.5 8487.2 8488.8 8498.0 8521.4 5109.5 5242.5 5243.4 5245.7 5255.8 3427.9 3537.2 3606.5 3611.7 3624.2 1040.3 1069.4 1085.5 1095.1 1096.6 11049.7 11243.4 11257.2 11278.3 11301.5 4552.2 4696.7 4740.3 4760.6 4767.3 9813.7 9867.3 9868.9 9870.9 9875.7 5232.7 5249.8 5258.6 5262.9 5264.6 4298.3 4687.6 4708.1 4735.6 4795.3 3661.3 3851.7 3959.7 3979.0 3984.9 1264.6 1298.2 1299.9 1308.1 1310.3 841.3 944.7 946.6 965.1 1003.2 5807.6 5837.4 5895.4 5905.2 5998.9 851.9 986.3 996.6 1003.3 1036.7 3497.9 4028.1 4028.1 4075.0 4222.8 10165.5 10221.0 10339.3 10364.3 10378.8 10215.8 10286.5 10295.4 10310.2 10358.0 7797.2 7841.9 7996.6 7999.3 8090.7 5154.2 5915.5 6071.2 6086.8 6106.2 9371.3 9375.0 9814.8 9826.2 9846.5 9631.3 9956.4 9975.6 9985.8 9989.3 6825.5 7153.3 7505.2 7512.0 7517.1 7643.6 7943.0 8193.1 8199.7 8322.8 7187.2 7278.4 7280.5 7389.1 7442.0 6563.1 6896.0 7143.4 7207.2 7253.7 6541.3 6794.7 6816.5 6832.2 6886.2 4943.2 4945.4 4980.4 4992.4 5084.3 4294.4 4995.9 5000.3 5064.7 5068.9 19:46:47 INFO - PROCESS | 1666 | 19:46:47 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:4418.33 Median:4781.95 stddev:1387.92 (29.0%) stddev-sans-first:1356.98 19:46:47 INFO - PROCESS | 1666 | Values: 301.3 306.5 317.4 320.2 324.2 4653.5 4810.5 5310.3 5329.3 5339.1 4213.5 4665.8 5258.4 5322.3 5331.7 3715.9 4017.4 4046.9 4063.1 4076.9 3327.6 3344.4 3693.5 3996.5 4071.6 3375.9 3454.8 3632.8 3874.7 4078.9 3265.0 3419.5 3805.2 3943.5 3979.6 3130.9 3386.1 3538.4 3539.6 3557.8 4431.0 4607.6 4948.4 5070.0 5129.9 3857.9 4160.5 4565.0 4567.1 4605.9 4783.4 5460.6 5510.9 5519.7 5564.0 3662.5 3862.4 3872.0 3872.1 3924.4 4954.4 5202.6 5232.5 5802.9 5822.6 3924.8 3945.7 3950.8 3961.9 4005.3 3578.2 3579.8 4020.2 4027.4 4035.1 3707.8 4483.1 4508.9 4509.3 4530.0 4780.5 5403.7 5529.8 5534.4 5582.5 1116.7 1261.4 1285.2 1304.7 1312.8 701.2 804.7 806.1 811.5 827.0 4015.5 4086.4 4147.7 4342.5 4379.5 4246.8 4618.3 4694.9 4737.8 4792.8 4027.4 4740.5 4997.7 5085.4 5241.5 2532.2 2733.1 2762.9 3042.2 3048.0 5648.6 5916.1 5934.4 5974.8 6022.4 5121.8 5923.0 5946.5 5979.5 5980.3 5453.6 5520.0 5671.8 6036.4 6062.6 4479.5 5092.3 5509.5 5661.9 5756.3 5314.4 5660.8 5687.1 5688.5 5697.4 5535.9 5900.7 5945.7 6003.8 6039.8 3828.4 3847.9 3860.3 3873.8 3936.3 4966.9 5099.6 5870.2 5870.2 5904.0 5129.7 5398.6 5642.3 5717.8 5739.3 4930.3 5407.2 5610.3 5639.4 5647.6 5084.6 5243.9 5277.5 5396.0 5422.2 4768.5 5364.7 5525.4 5597.0 5636.8 4496.7 4683.9 5330.5 5521.0 5630.2 19:46:47 INFO - PROCESS | 1666 | 19:46:47 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:22261.71 Median:4592.68 stddev:34233.67 (745.4%) stddev-sans-first:34291.74 19:46:47 INFO - PROCESS | 1666 | Values: 788.8 806.3 810.2 845.7 875.2 15302.8 15620.6 15978.6 15999.3 15999.7 14408.5 14465.6 14586.1 14603.8 14605.9 730.5 753.8 904.8 910.4 910.7 899.5 926.8 929.5 931.6 936.1 965.4 975.5 975.7 976.8 977.1 757.3 778.7 783.0 963.8 964.3 564.8 700.8 716.8 717.6 719.1 15050.8 15063.8 15088.9 15093.8 15099.3 13962.7 14108.0 14110.2 14228.2 14330.6 10536.7 12689.2 12784.6 12876.4 13005.6 4573.6 4611.7 4654.5 4771.7 4788.0 98751.0 99616.5 100651.0 102200.5 103625.0 9455.8 9482.7 9535.0 9681.8 9809.0 19031.8 19057.4 19071.0 19106.3 19178.8 7934.6 8008.0 8027.0 8041.1 8111.7 4364.5 4392.4 4394.8 4397.7 4402.8 3416.5 3524.6 3529.6 3532.8 3533.0 582.3 608.9 644.6 660.6 666.4 583.9 592.8 645.0 653.8 685.5 8958.6 9344.5 9353.2 9374.3 9389.1 551.3 608.7 619.9 650.4 656.9 4372.0 4418.1 4423.0 4423.2 4563.0 91785.0 94614.0 100556.5 106813.0 139747.0 99942.5 110062.0 118164.8 123523.8 126454.0 101501.0 106704.5 107350.9 108071.3 111349.0 8360.7 8432.4 8586.4 8606.3 8865.2 53687.7 55478.0 55508.4 56362.0 59655.7 73743.0 84945.0 86141.6 87601.0 88191.0 11446.4 11613.9 11620.2 11623.8 11815.4 12540.7 12631.2 12743.7 12881.2 13351.6 11748.5 11825.1 11998.1 12243.6 12320.3 11766.9 11859.6 11870.7 12297.4 12368.5 11650.9 11890.2 11920.5 12070.9 12212.4 8847.9 9899.4 9962.2 10228.8 10405.4 9660.0 9969.2 10204.2 10208.1 10217.2 19:46:47 INFO - PROCESS | 1666 | -------- Summary: end -------- 19:46:47 INFO - PROCESS | 1666 | 19:46:48 INFO - TEST-INFO | 1666: exit 0 19:46:48 INFO - TEST-OK | dromaeo_css | took 1115416ms 19:46:48 INFO - TEST-START | kraken 19:46:48 INFO - operating with platform_type : mac_ 19:46:48 INFO - Initialising browser for kraken test... 19:46:48 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmp31YMpR/profile http://localhost:49277/getInfo.html) 19:46:50 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 19:46:50 INFO - PROCESS | 1673 | colorDepth:24 19:46:50 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 19:46:50 INFO - PROCESS | 1673 | __metrics 19:46:50 INFO - PROCESS | 1673 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:46:50 ERROR - PROCESS | 1673 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:46:51 INFO - TEST-INFO | 1673: exit 0 19:46:51 INFO - Browser initialized. 19:46:51 INFO - Running cycle 1/1 for kraken test... 19:46:51 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmp31YMpR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:47:03 INFO - PROCESS | 1674 | RSS: Main: 242274304 19:47:03 INFO - PROCESS | 1674 | 19:47:06 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 19:47:06 INFO - PROCESS | 1674 | RSS: Main: 292483072 19:47:06 INFO - PROCESS | 1674 | 19:47:09 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 19:47:10 INFO - PROCESS | 1674 | RSS: Main: 302133248 19:47:10 INFO - PROCESS | 1674 | 19:47:13 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 19:47:14 INFO - PROCESS | 1674 | RSS: Main: 388456448 19:47:14 INFO - PROCESS | 1674 | 19:47:16 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 19:47:17 INFO - PROCESS | 1674 | RSS: Main: 288755712 19:47:17 INFO - PROCESS | 1674 | 19:47:19 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:47:19 INFO - PROCESS | 1674 | RSS: Main: 293523456 19:47:19 INFO - PROCESS | 1674 | 19:47:22 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 19:47:23 INFO - PROCESS | 1674 | RSS: Main: 329760768 19:47:23 INFO - PROCESS | 1674 | 19:47:26 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 19:47:26 INFO - PROCESS | 1674 | RSS: Main: 330493952 19:47:26 INFO - PROCESS | 1674 | 19:47:29 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 19:47:30 INFO - PROCESS | 1674 | RSS: Main: 330194944 19:47:30 INFO - PROCESS | 1674 | 19:47:31 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:47:32 INFO - PROCESS | 1674 | RSS: Main: 310218752 19:47:32 INFO - PROCESS | 1674 | 19:47:34 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:47:34 INFO - PROCESS | 1674 | RSS: Main: 317480960 19:47:34 INFO - PROCESS | 1674 | 19:47:37 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:47:37 INFO - PROCESS | 1674 | RSS: Main: 320966656 19:47:37 INFO - PROCESS | 1674 | 19:47:40 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:47:41 INFO - PROCESS | 1674 | RSS: Main: 323117056 19:47:41 INFO - PROCESS | 1674 | 19:47:44 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:47:44 INFO - PROCESS | 1674 | RSS: Main: 323792896 19:47:44 INFO - PROCESS | 1674 | 19:47:46 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 19:47:46 INFO - PROCESS | 1674 | RSS: Main: 367484928 19:47:46 INFO - PROCESS | 1674 | 19:47:46 INFO - PROCESS | 1674 | __start_tp_report 19:47:46 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 19:47:46 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 19:47:46 INFO - PROCESS | 1674 | |i|pagename|runs| 19:47:46 INFO - PROCESS | 1674 | |0;ai-astar;111;106;112;113;111;114;118;162;159;112 19:47:46 INFO - PROCESS | 1674 | |1;audio-beat-detection;156;118;121;194;101;131;164;140;165;113 19:47:46 INFO - PROCESS | 1674 | |2;audio-dft;182;183;185;185;184;184;183;181;179;194 19:47:46 INFO - PROCESS | 1674 | |3;audio-fft;77;74;58;110;56;56;64;68;103;80 19:47:46 INFO - PROCESS | 1674 | |4;audio-oscillator;78;85;92;71;75;69;95;69;68;97 19:47:46 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;87;83;103;131;104;83;86;83;86;84 19:47:46 INFO - PROCESS | 1674 | |6;imaging-darkroom;93;92;93;92;91;92;93;93;93;92 19:47:46 INFO - PROCESS | 1674 | |7;imaging-desaturate;86;84;85;87;83;87;68;136;134;94 19:47:46 INFO - PROCESS | 1674 | |8;json-parse-financial;54;54;56;55;54;54;54;53;55;54 19:47:46 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;70;74;78;77;71;72;72;70;72;71 19:47:46 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;86;86;86;84;84;90;86;88;126;86 19:47:46 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;141;144;131;138;142;144;143;139;153;141 19:47:46 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;164;165;167;165;165;165;163;164;221;173 19:47:46 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;65;64;66;67;65;65;66;66;66;65 19:47:46 INFO - PROCESS | 1674 | __end_tp_report 19:47:46 INFO - PROCESS | 1674 | __start_cc_report 19:47:46 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1961 19:47:46 INFO - PROCESS | 1674 | __end_cc_report 19:47:46 INFO - PROCESS | 1674 | __startTimestamp1464835666670__endTimestamp 19:47:46 INFO - PROCESS | 1674 | 19:47:46 INFO - PROCESS | 1674 | ------- Summary: start ------- 19:47:46 INFO - PROCESS | 1674 | Number of tests: 14 19:47:46 INFO - PROCESS | 1674 | 19:47:46 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:121.80 Median:113.50 stddev:20.62 (18.2%) stddev-sans-first:21.50 19:47:46 INFO - PROCESS | 1674 | Values: 111.0 106.0 112.0 113.0 111.0 114.0 118.0 162.0 159.0 112.0 19:47:46 INFO - PROCESS | 1674 | 19:47:46 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:140.30 Median:148.00 stddev:28.96 (19.6%) stddev-sans-first:30.15 19:47:46 INFO - PROCESS | 1674 | Values: 156.0 118.0 121.0 194.0 101.0 131.0 164.0 140.0 165.0 113.0 19:47:46 INFO - PROCESS | 1674 | 19:47:46 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:184.00 Median:184.00 stddev:3.97 (2.2%) stddev-sans-first:4.15 19:47:46 INFO - PROCESS | 1674 | Values: 182.0 183.0 185.0 185.0 184.0 184.0 183.0 181.0 179.0 194.0 19:47:46 INFO - PROCESS | 1674 | 19:47:46 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:74.60 Median:66.00 stddev:18.91 (28.7%) stddev-sans-first:20.04 19:47:46 INFO - PROCESS | 1674 | Values: 77.0 74.0 58.0 110.0 56.0 56.0 64.0 68.0 103.0 80.0 19:47:46 INFO - PROCESS | 1674 | 19:47:46 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:79.90 Median:81.50 stddev:11.45 (14.0%) stddev-sans-first:12.12 19:47:46 INFO - PROCESS | 1674 | Values: 78.0 85.0 92.0 71.0 75.0 69.0 95.0 69.0 68.0 97.0 19:47:46 INFO - PROCESS | 1674 | 19:47:46 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:93.00 Median:83.50 stddev:15.56 (18.6%) stddev-sans-first:16.36 19:47:46 INFO - PROCESS | 1674 | Values: 87.0 83.0 103.0 131.0 104.0 83.0 86.0 83.0 86.0 84.0 19:47:46 INFO - PROCESS | 1674 | 19:47:46 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:92.40 Median:93.00 stddev:0.70 (0.8%) stddev-sans-first:0.71 19:47:46 INFO - PROCESS | 1674 | Values: 93.0 92.0 93.0 92.0 91.0 92.0 93.0 93.0 93.0 92.0 19:47:46 INFO - PROCESS | 1674 | 19:47:46 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:85.50 stddev:22.37 (26.2%) stddev-sans-first:23.52 19:47:46 INFO - PROCESS | 1674 | Values: 86.0 84.0 85.0 87.0 83.0 87.0 68.0 136.0 134.0 94.0 19:47:46 INFO - PROCESS | 1674 | 19:47:46 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:54.00 stddev:0.82 (1.5%) stddev-sans-first:0.87 19:47:46 INFO - PROCESS | 1674 | Values: 54.0 54.0 56.0 55.0 54.0 54.0 54.0 53.0 55.0 54.0 19:47:46 INFO - PROCESS | 1674 | 19:47:46 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:72.70 Median:72.00 stddev:2.79 (3.9%) stddev-sans-first:2.78 19:47:46 INFO - PROCESS | 1674 | Values: 70.0 74.0 78.0 77.0 71.0 72.0 72.0 70.0 72.0 71.0 19:47:46 INFO - PROCESS | 1674 | 19:47:46 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:86.00 stddev:12.70 (14.8%) stddev-sans-first:13.38 19:47:46 INFO - PROCESS | 1674 | Values: 86.0 86.0 86.0 84.0 84.0 90.0 86.0 88.0 126.0 86.0 19:47:46 INFO - PROCESS | 1674 | 19:47:46 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:141.60 Median:142.50 stddev:5.54 (3.9%) stddev-sans-first:5.87 19:47:46 INFO - PROCESS | 1674 | Values: 141.0 144.0 131.0 138.0 142.0 144.0 143.0 139.0 153.0 141.0 19:47:46 INFO - PROCESS | 1674 | 19:47:46 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.20 Median:165.00 stddev:17.72 (10.7%) stddev-sans-first:18.60 19:47:46 INFO - PROCESS | 1674 | Values: 164.0 165.0 167.0 165.0 165.0 165.0 163.0 164.0 221.0 173.0 19:47:46 INFO - PROCESS | 1674 | 19:47:46 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:66.00 stddev:0.85 (1.3%) stddev-sans-first:0.88 19:47:46 INFO - PROCESS | 1674 | Values: 65.0 64.0 66.0 67.0 65.0 65.0 66.0 66.0 66.0 65.0 19:47:46 INFO - PROCESS | 1674 | -------- Summary: end -------- 19:47:46 INFO - PROCESS | 1674 | 19:47:47 INFO - TEST-INFO | 1674: exit 0 19:47:47 INFO - TEST-OK | kraken | took 59270ms 19:47:47 INFO - SUITE-END | took 1174s 19:47:47 INFO - Completed test suite (00:19:35) 19:47:47 INFO - javascript benchmark 19:47:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3466.2547552276264, 3475.1138459692775, 3481.2689335239606, 3491.0748651607314, 3540.309639620275, 4646.233371493704, 5665.093585026397, 5756.654600723959, 5783.710814459276, 5803.622764520309, 5598.1602759586085, 5686.118651609007, 5703.973284808732, 5747.310322761275, 5805.100209467529, 5156.458571849958, 5178.637495375326, 5248.317718696583, 5261.368635763694, 5368.597355198356, 5340.49265319794, 5351.57187425007, 5386.084365657828, 5393.838184854441, 5439.102548079562, 5168.689878607277, 5261.15821468567, 5265.236540701577, 5296.443873393277, 5324.148136298174, 5195.480451954812, 5221.686698798084, 5249.212618107276, 5255.264263003182, 5255.60582956276, 5249.081410753103, 5282.867928301823, 5292.364916210041, 5316.946830531684, 5358.276632654581, 4763.14263432582, 5604.719764011824, 5656.802011929604, 5697.743601537941, 5752.654840709571, 6212.844678883064, 6217.502599792006, 6220.688965551704, 6223.875522489525, 6228.096925945758, 6205.131281620576, 6230.2523697156175, 6242.812715618538, 6253.656048917308, 6259.0924315973925, 4851.611871050308, 5891.970540147315, 5936.13925980739, 5939.910901336483, 5959.507701525309, 6289.7484100636375, 6298.779542715983, 6299.7795077173, 6301.0233413820015, 6306.2747784004505, 6219.222597175353, 6236.937630623726, 6249.875002499924, 6254.499640028786, 6265.342139075331, 6110.29731580865, 6153.723082461211, 6203.131561581382, 6219.346968568235, 6290.528210384239, 6139.570230083851, 6203.348648391944, 6208.627482350983, 6209.782657607052, 6216.689165541705, 6117.827404800828, 6196.47329976957, 6211.937880621226, 6224.564280500322, 6232.937670623326, 4126.938095928564, 4239.236937351245, 4971.304017437562, 5014.498550145029, 5017.9247311290355, 3810.580836108026, 4439.844605438794, 4501.144782491382, 4521.321801729767, 4539.228331183706, 4971.6576524338225, 5662.320521537442, 5809.448102430347, 5813.79651712188, 5864.06175011996, 6123.282621474291, 6199.256089269226, 6232.8130156095385, 6266.530010249249, 6283.963146080937, 5680.176374425685, 5734.426557344233, 5801.796937107265, 5806.941930580725, 5872.0, 4623.491415944243, 5434.10337294342, 5459.235707001023, 5470.8085217017215, 5499.395066542677, 6025.855087533355, 6346.777862774781, 6360.268569114519, 6361.522885783585, 6374.298827129012, 5547.389787123332, 6160.137580738789, 6180.536459765445, 6262.906056409066, 6345.206849143857, 5591.580631452576, 6290.150829637941, 6343.333949935375, 6365.236171659245, 6369.33122022181, 6213.00591905293, 6284.371562843679, 6296.5277604178955, 6312.5581209314905, 6321.146645202839, 6096.829073522206, 6113.57204995646, 6117.096718007379, 6135.049067394382, 6148.815535533941, 6097.878042439215, 6139.055828126434, 6147.108669242845, 6159.199304090619, 6206.844828879133, 6169.043798211284, 6200.3179650237535, 6226.750929962751, 6241.31345551998, 6241.8751624966335, 5924.229850119457, 6032.758689652364, 6068.939310607014, 6089.056196289582, 6133.539984501091, 6210.689465526615, 6211.81364559064, 6216.751329946752, 6217.4715149212825, 6217.720202590804, 5899.91150132757, 6040.465739638176, 6066.423689749467, 6092.268927728701, 6107.552954153702], "value": 5703.841644281129, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.0194268262863, 513.9948600513995, 522.5323335614625, 553.4714347797853, 574.9195112684222, 24469.877650611743, 43310.0, 44416.55583444165, 44525.0, 46625.53374466254, 37421.81289093557, 45377.773111134484, 45748.085038299214, 45809.77095114528, 45871.0, 9543.856842147356, 9777.46223957682, 9788.657396991106, 9796.657117000907, 9808.803823923517, 9611.0, 9766.706998790047, 9786.412815231073, 9789.265805064613, 9797.706068817946, 9758.658446954358, 9786.559604817765, 9797.608095676165, 9813.607455701764, 9826.557804898797, 9567.37812042215, 9579.425234485883, 9585.568649410723, 9592.952035239849, 9601.183899368598, 9581.520923953774, 9704.223662107015, 9710.90289097107, 9725.0, 9730.12428881404, 41272.58727412747, 41697.0, 41777.373339399936, 41796.58203417957, 41932.37101443481, 3442.0, 3498.0904964709102, 3580.5882323532865, 3641.7268704847243, 3867.7292589518743, 31014.84492577545, 31642.525362119588, 31715.20711982196, 32212.0, 32829.0, 3075.5540446635337, 3116.5792617996735, 3151.3854798314337, 3171.0645359618966, 3234.8867789627484, 487029.0, 488111.0, 488735.0, 488920.0, 489839.0, 61579.0, 62965.37034629595, 62976.37023629797, 63045.05432418518, 63127.0, 35222.471662925076, 35259.4711079334, 36170.09574760652, 37773.81113094417, 37800.62199378026, 16096.275667594986, 16243.431479898383, 16272.918635406748, 16302.592435189215, 16339.18304084791, 12279.447424865903, 12358.134930554954, 12367.93816030932, 12369.690757730948, 12379.257244565355, 20075.39773806759, 20183.697244541345, 20193.192272308945, 20333.0, 20352.491187720425, 7801.29788319054, 7805.609719514002, 7842.568658723825, 7859.685612575548, 7877.921220787834, 915.2174890468686, 1013.8884722680499, 1040.3913710479303, 1069.6256310291335, 1081.4430568257353, 30834.69165308363, 31025.0, 31092.0, 31532.842335788173, 31790.523142152888, 1064.4677661169414, 1096.4079397125463, 1121.5401685308984, 1123.713453069462, 1138.2146319039837, 12256.571020014257, 15869.0, 15915.522534323989, 16060.036397816168, 16564.420245291356, 714941.0, 751940.0, 759482.4051759522, 759726.0, 1025599.0, 58424.0, 59134.0, 59538.70230648906, 59639.40360596339, 59998.40001600015, 1301.2517802263546, 1353.2962859312904, 1421.8862491000696, 1449.5506393018197, 1468.9779653305209, 25911.87044064843, 29798.404031918806, 30866.53700194454, 32346.029619111465, 33159.0, 57785.71107144438, 57926.420735792104, 58659.70670146622, 58666.4133358661, 58721.412785871595, 571298.0, 573097.0, 573191.0, 581824.0, 584107.0, 22051.0, 24431.14490992807, 26537.0, 26828.59757103684, 27631.032913847917, 48525.0, 48606.0, 49288.26067608917, 49577.0, 49770.75114624404, 38393.61606383901, 39734.0, 40076.39885401663, 40560.98597535085, 40758.0, 38382.040448989, 38934.0, 39101.60898391093, 39659.00852478711, 39776.0, 37862.62137378701, 38713.2257354857, 38845.14435695538, 39042.609573903894, 39149.412758808074, 32143.678563215002, 34518.65481345255, 34572.48141277933, 34809.47785783165, 34988.65011349854, 23235.3029409118, 24994.0, 25530.617040744775, 25812.0, 25834.095806646677], "value": 20249.71866454771, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.2081400568607, 389.6882494004797, 390.7499200511671, 392.70547089682753, 405.5599674353326, 19475.805241947575, 20202.69695954558, 21841.235556755517, 21880.781192188075, 21882.343529694095, 11630.0, 11876.28742275464, 12180.756384872297, 12191.51233950641, 12220.755584888297, 5184.766685499152, 5355.464453554652, 5416.214648875911, 5419.83740487786, 5421.268128802601, 3841.61583841615, 4062.512498500184, 5660.094384898417, 5976.521878249751, 5980.671063091529, 5903.527717782567, 6040.093985902128, 6041.788537401192, 6046.516278697693, 6051.576389652726, 5832.445917637818, 5967.850803729898, 5972.611780234292, 6014.097885317194, 6017.1275165100815, 3709.55485341758, 5380.488853558905, 5731.541476681856, 5743.994800909866, 5777.855553611152, 13482.39329230187, 13615.0, 16130.677386452322, 26043.869780651166, 26071.56606386648, 2007.6687346587796, 2133.232036466871, 2168.750593681731, 2249.5950728868797, 2329.9631663909568, 10084.243681723827, 10217.386956782542, 10498.685039448828, 11172.720681982933, 15981.760273595906, 1869.5513076861591, 1878.9906050469797, 1927.1424216223786, 1940.6312800567846, 1965.9705104423444, 115745.0, 119439.0, 123820.0, 129327.35336323352, 132410.33794830964, 11440.771184576428, 11608.30350178992, 11652.785970665547, 11667.474963626657, 11769.0, 14608.415663373346, 14686.41254349814, 14691.55925322242, 14714.484993025193, 14715.484958026418, 8273.0, 8313.75763452242, 8316.209960053786, 8349.4155409122, 8464.44981076228, 8076.676932922617, 8091.959540202261, 8174.427790054758, 8190.959045204736, 8205.71280005197, 4524.954750452519, 4572.95427045732, 4586.220342541776, 4626.814927402934, 4649.3026046093355, 1639.7704321395015, 1759.3992853633192, 1792.3099606651533, 1839.0069362544343, 1980.1188471130497, 821.482466046387, 890.8708237305684, 943.4716558727134, 948.8671585977969, 1010.7119470950744, 20245.696314555295, 20940.685889711665, 21028.0, 21088.17374017884, 21367.78632213689, 843.8017065989487, 977.6236149082655, 994.8905620381752, 995.1590905684685, 1031.7523794289316, 10978.725531861768, 11132.202985549222, 11160.0, 11242.66272011841, 11248.831267530994, 576526.0, 585179.0, 591574.0, 597096.0, 604041.0, 81447.59276203699, 81718.0, 114621.0, 130558.0, 140168.29915850356, 961.0293603460556, 970.5341436110706, 981.651513712636, 982.0, 987.2003677021636, 18313.992730399423, 18359.80661257014, 18479.81520184781, 18530.629387412446, 18820.905895470434, 51411.0, 51734.74132629313, 52863.0, 54937.0, 55707.0, 72726.0, 78178.6091069541, 78202.0, 78469.31193342205, 80271.19728802638, 8241.0, 8267.917320826955, 8298.543580103153, 8396.328293736364, 8479.36404769657, 11968.640940771442, 12812.103152779217, 13128.934355328161, 13184.274864882524, 13257.535986240435, 10471.267011309135, 11027.83458248111, 11136.554537818396, 11480.483378248166, 11495.597654082067, 11280.774384512428, 11308.773824523629, 11363.54545818191, 11572.421378931189, 11598.0, 9569.234461243223, 11012.008919197311, 11435.485403157045, 11900.76198476043, 11927.4036298183, 8019.077806052145, 8144.470609410369, 8172.305354044735, 8188.713395031145, 8203.794905127419, 10944.671659850217, 11004.174686898354, 11053.668389948314, 11078.612248571262, 11099.0], "value": 9310.038933287356, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1404.3539971613056, 1548.8218854831698, 1562.1954693332934, 1792.9152862518176, 1950.5318723506355, 7653.158152603216, 8387.664493420256, 8596.699115530966, 8596.8710469343, 8755.95622021889, 8392.412531122824, 8617.956910215456, 8620.655173793057, 8632.136786321365, 8633.611487483062, 2082.8125468707813, 2152.5587254612765, 2156.5363446858923, 2341.063574570177, 2372.074890792592, 2180.367693368929, 2195.5828392605436, 2308.595995700746, 2398.4603464220586, 2402.7356990731005, 2330.883455827202, 2377.108584280895, 2377.6552399902002, 2382.29722231942, 2422.4912768318673, 2021.161218094489, 2308.157522504282, 2372.7864492195786, 2376.2277259890607, 2379.3694670912223, 2108.8629239099564, 2189.27753841232, 2264.750877403484, 2346.3312955807683, 2356.4108972756812, 7760.495567788075, 8487.193716596914, 8488.830223395498, 8497.95751021247, 8521.40350175488, 5109.489051094898, 5242.528172464458, 5243.449437809013, 5245.711485868275, 5255.76349064293, 3427.9314413711836, 3537.1510837399096, 3606.5311509503595, 3611.7291203159607, 3624.220792529619, 1040.2926010312983, 1069.4064794039305, 1085.484394912418, 1095.0856035210609, 1096.6490722968574, 11049.723756906062, 11243.437828108643, 11257.211995160342, 11278.323300601991, 11301.49143288554, 4552.157850797613, 4696.671233013656, 4740.265258884879, 4760.595349395271, 4767.261074533453, 9813.656522021838, 9867.309288349747, 9868.901310986941, 9870.901290986998, 9875.654352097643, 5232.686038837682, 5249.790008399697, 5258.631895767336, 5262.92105618416, 5264.552513036435, 4298.312270036779, 4687.5781179693995, 4708.105459962665, 4735.59747421473, 4795.328653988445, 3661.3226553087766, 3851.7111216658864, 3959.6832253419657, 3979.0, 3984.900377490586, 1264.6016504800957, 1298.2275546050084, 1299.883010529057, 1308.129374993749, 1310.298990040343, 841.2722994609619, 944.7213072143734, 946.5740416812462, 965.0840348662653, 1003.1874181912674, 5807.622504537276, 5837.416258374129, 5895.4399332063385, 5905.232319798484, 5998.910016349758, 851.9095557686171, 986.2800155886243, 996.5963784667222, 1003.2826529031777, 1036.6734478639355, 3497.877574284939, 4028.133951200506, 4028.133951200506, 4075.0, 4222.78886055696, 10165.4917254137, 10221.0, 10339.276250662382, 10364.274500785023, 10378.844317335246, 10215.795684086277, 10286.485675716185, 10295.433751143759, 10310.226732995055, 10358.0, 7797.181295963956, 7841.882371764314, 7996.6401511930935, 7999.3200577950365, 8090.716824910982, 5154.226865970135, 5915.526757859447, 6071.208993346885, 6086.847828804138, 6106.2061931948565, 9371.250299975849, 9374.95312523433, 9814.75463113428, 9826.213902887896, 9846.458444785605, 9631.325807193709, 9956.352837065857, 9975.60097596088, 9985.800283994426, 9989.250806189419, 6825.453963682794, 7153.320434558708, 7505.249475052669, 7512.0, 7517.135529414188, 7643.5796031218815, 7943.046834379911, 8193.09875913661, 8199.71301004462, 8322.833543329221, 7187.209406965334, 7278.417726581966, 7280.453965952469, 7389.076365454318, 7441.962790186014, 6563.146790917151, 6896.0, 7143.357097861218, 7207.1711753146965, 7253.709851605877, 6541.280459149394, 6794.694238759366, 6816.48876334267, 6832.248452670302, 6886.208086069965, 4943.209086546135, 4945.357103576656, 4980.402351717817, 4992.350994370854, 5084.313617661568, 4294.420253265769, 4995.900081998267, 5000.274960130688, 5064.7467626619255, 5068.898622027613], "value": 4526.084464650465, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.28444943259757, 306.4835751758287, 317.370020509289, 320.1963072687556, 324.2121644404099, 4653.511381304957, 4810.470848206694, 5310.309659744233, 5329.253904453375, 5339.065663508885, 4213.494380674316, 4665.76671166442, 5258.421573626898, 5322.270262164802, 5331.654701418342, 3715.9071023224365, 4017.3572228443454, 4046.9190616187657, 4063.1061166543313, 4076.8980775480554, 3327.633960264369, 3344.3812894614452, 3693.4829123922673, 3996.5429903133727, 4071.5928407159345, 3375.9324813503717, 3454.842627719717, 3632.7648599476174, 3874.682607913309, 4078.877633670995, 3264.955214331415, 3419.5296022710227, 3805.219929914361, 3943.467631869704, 3979.582143874895, 3130.9217269568217, 3386.102682789063, 3538.3630946429635, 3539.557555305587, 3557.786532808014, 4431.028192454359, 4607.585317321424, 4948.38145231846, 5070.048031507859, 5129.9230511542355, 3857.9228415431535, 4160.479940007499, 4564.968588977906, 4567.099891568844, 4605.861824145281, 4783.425988881322, 5460.645058071213, 5510.889782204374, 5519.724013799334, 5563.972180139073, 3662.468942003421, 3862.3820188769664, 3872.0, 3872.1481274119647, 3924.4309575111447, 4954.380702412199, 5202.621305354122, 5232.529072383504, 5802.854928626776, 5822.592418530747, 3924.843006279774, 3945.7373640434785, 3950.762954222756, 3961.861334853324, 4005.319095753671, 3578.177019285579, 3579.83890724918, 4020.1758639478835, 4027.3958906163725, 4035.0517628357898, 3707.758995665327, 4483.103379324083, 4508.909821803545, 4509.278515437514, 4529.954700452953, 4780.4741478437345, 5403.729813509309, 5529.811090615456, 5534.378793707219, 5582.525485333792, 1116.6873275482878, 1261.3882267100448, 1285.190330092036, 1304.6757541095794, 1312.7899536074374, 701.235653138082, 804.7022601637432, 806.1293802693077, 811.5333683132222, 826.9786813285588, 4015.4980627421573, 4086.3870419436726, 4147.709660323749, 4342.522322544518, 4379.4525684289465, 4246.787660616957, 4618.330342100444, 4694.920168361296, 4737.768180273085, 4792.760361981887, 4027.395890616431, 4740.530435564917, 4997.7251251180805, 5085.440601533828, 5241.453771137523, 2532.2150133458686, 2733.139061195717, 2762.9171124866284, 3042.1938186380626, 3048.00355766973, 5648.604597678205, 5916.101110455846, 5934.406559344031, 5974.790882319098, 6022.427869352405, 5121.8463446096675, 5922.96348139074, 5946.502468963046, 5979.4618484336615, 5980.312264089598, 5453.590980676465, 5520.006398848246, 5671.829845104653, 6036.396360364016, 6062.636241825505, 4479.50725420197, 5092.264194299337, 5509.531689806251, 5661.915071273852, 5756.30924289088, 5314.3596636304255, 5660.830175094754, 5687.061634830372, 5688.459596338341, 5697.373288938131, 5535.868690554085, 5900.70496475166, 5945.702714864153, 6003.849903752441, 6039.818805435844, 3828.4448754930936, 3847.9230415392094, 3860.3437415638846, 3873.8256778445602, 3936.3308237599103, 4966.932109596454, 5099.643024988215, 5870.207521984648, 5870.236869206976, 5904.0, 5129.666571672829, 5398.598882703872, 5642.266505354278, 5717.771289148387, 5739.282589676291, 4930.260460930889, 5407.161889907071, 5610.298712660918, 5639.436056394492, 5647.576431767552, 5084.618653601069, 5243.921341179962, 5277.514113003916, 5395.973020134874, 5422.213779001943, 4768.546988036131, 5364.7049412282695, 5525.392206857162, 5597.0, 5636.830895073154, 4496.7301961882395, 4683.929741053955, 5330.493603107699, 5520.951019306319, 5630.18362337467], "value": 3976.7159539015734, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [788.7909703928457, 806.3226889412894, 810.197904074966, 845.6744153500904, 875.2254254984339, 15302.770458443118, 15620.609484762857, 15978.600534986632, 15999.280032398541, 15999.680006399894, 14408.495702650409, 14465.566033019, 14586.051906626062, 14603.78094328586, 14605.926970365133, 730.4777084384664, 753.7625647920914, 904.8145130248316, 910.4491782471575, 910.653951498433, 899.491787140267, 926.8285367207088, 929.4794914847707, 931.6133804471136, 936.1060187520935, 965.3821554205314, 975.4683697384907, 975.6633961283381, 976.8485884687884, 977.0522593084696, 757.322196634015, 778.7002004228357, 782.9586649755811, 963.7831487915233, 964.310517979641, 564.8277275430992, 700.8072779985494, 716.7634680555404, 717.5981450387748, 719.0867598150372, 15050.849491505054, 15063.774043389358, 15088.924555377263, 15093.849061509463, 15099.320530576151, 13962.720745585133, 14108.0, 14110.223937683424, 14228.217448040354, 14330.64173395663, 10536.68389948295, 12689.23864568129, 12784.61646150617, 12876.356182190853, 13005.609831705064, 4573.634109271284, 4611.723296602215, 4654.534546545352, 4771.71369717818, 4787.952120478785, 98751.01248987562, 99616.50191748995, 100651.0, 102200.4669929959, 103625.0, 9455.763605909908, 9482.715518534455, 9535.0, 9681.757956051155, 9808.950955245178, 19031.809681903003, 19057.428277151706, 19071.0, 19106.33127840519, 19178.80821191798, 7934.563599001995, 8007.959960200162, 8027.03675558925, 8041.115477297492, 8111.716089936824, 4364.476262848479, 4392.4289842320295, 4394.846180383671, 4397.714148580332, 4402.762400766556, 3416.5216869637775, 3524.6475352465063, 3529.5588051493564, 3532.788032718045, 3533.0107569880474, 582.3419535924463, 608.8782243551308, 644.6261168522246, 660.6267458885715, 666.4102269491437, 583.8744669895908, 592.7984485275027, 644.9645269510128, 653.8496145886472, 685.475611157465, 8958.596863141174, 9344.532773361305, 9353.158215760614, 9374.250059995184, 9389.10803473669, 551.2980337036817, 608.6774009774826, 619.8667286533417, 650.3561474140566, 656.8883289840738, 4372.0, 4418.1163767246035, 4423.0, 4423.181711383404, 4563.018950925567, 91785.0, 94614.0, 100556.49165262526, 106813.0, 139747.0, 99942.50028749956, 110062.0, 118164.81835181538, 123523.76476235302, 126454.0, 101501.0, 106704.46647766711, 107350.92649073566, 108071.29821754519, 111349.0, 8360.70737524208, 8432.36757243197, 8586.441881277697, 8606.26846718036, 8865.246454051477, 53687.73156134194, 55478.0, 55508.444915550324, 56362.0, 59655.70172149112, 73743.0, 84945.0, 86141.56929215314, 87601.0, 88191.0, 11446.37044962535, 11613.941930290632, 11620.244684095507, 11623.76752464963, 11815.350155741517, 12540.749185016432, 12631.242125472501, 12743.745125097632, 12881.227126372447, 13351.599452016455, 11748.485647874017, 11825.113116516124, 11998.100142489522, 12243.571474998333, 12320.260784352968, 11766.882331176921, 11859.644210673694, 11870.703232419259, 12297.378976866776, 12368.505259789508, 11650.941745291218, 11890.227135236182, 11920.523179073087, 12070.939645301718, 12212.414123661898, 8847.894013248344, 9899.359651603016, 9962.203023758228, 10228.846567301347, 10405.375677459377, 9660.0, 9969.152622027208, 10204.18366530661, 10208.081272685196, 10217.233707472118], "value": 7508.24896922769, "unit": "score"}], "name": "dromaeo_css", "value": 7250.956229042384, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 106.0, 112.0, 113.0, 111.0, 114.0, 118.0, 162.0, 159.0, 112.0], "value": 121.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 118.0, 121.0, 194.0, 101.0, 131.0, 164.0, 140.0, 165.0, 113.0], "value": 140.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 183.0, 185.0, 185.0, 184.0, 184.0, 183.0, 181.0, 179.0, 194.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 58.0, 110.0, 56.0, 56.0, 64.0, 68.0, 103.0, 80.0], "value": 74.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 85.0, 92.0, 71.0, 75.0, 69.0, 95.0, 69.0, 68.0, 97.0], "value": 79.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 103.0, 131.0, 104.0, 83.0, 86.0, 83.0, 86.0, 84.0], "value": 93.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 93.0, 92.0, 91.0, 92.0, 93.0, 93.0, 93.0, 92.0], "value": 92.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 85.0, 87.0, 83.0, 87.0, 68.0, 136.0, 134.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 56.0, 55.0, 54.0, 54.0, 54.0, 53.0, 55.0, 54.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 74.0, 78.0, 77.0, 71.0, 72.0, 72.0, 70.0, 72.0, 71.0], "value": 72.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 86.0, 84.0, 84.0, 90.0, 86.0, 88.0, 126.0, 86.0], "value": 90.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 144.0, 131.0, 138.0, 142.0, 144.0, 143.0, 139.0, 153.0, 141.0], "value": 141.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 167.0, 165.0, 165.0, 165.0, 163.0, 164.0, 221.0, 173.0], "value": 171.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 66.0, 67.0, 65.0, 65.0, 66.0, 66.0, 66.0, 65.0], "value": 65.5, "unit": "score"}], "name": "kraken", "value": 1475.8999999999999, "alertThreshold": 2.0}]} 19:47:47 INFO - Return code: 0 19:47:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:47:47 ERROR - # TBPL SUCCESS # 19:47:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:47:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:47:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:47:47 INFO - 2016-06-01 19:47:47.955 screenresolution[1678:10118] starting screenresolution argv=screenresolution get 19:47:47 INFO - 2016-06-01 19:47:47.957 screenresolution[1678:10118] Display 0: 1600x1200x32@60 19:47:47 INFO - 2016-06-01 19:47:47.963 screenresolution[1679:10121] starting screenresolution argv=screenresolution list 19:47:47 INFO - Available Modes on Display 0 19:47:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:47:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:47:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:47:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:47:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:47:48 INFO - Intel Iris: 19:47:48 INFO - Chipset Model: Intel Iris 19:47:48 INFO - Type: GPU 19:47:48 INFO - Bus: Built-In 19:47:48 INFO - VRAM (Dynamic, Max): 1536 MB 19:47:48 INFO - Vendor: Intel (0x8086) 19:47:48 INFO - Device ID: 0x0a2e 19:47:48 INFO - Revision ID: 0x0009 19:47:48 INFO - Displays: 19:47:48 INFO - Display: 19:47:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:47:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:47:48 INFO - Display Serial Number: 1600x1200 60 19:47:48 INFO - Main Display: Yes 19:47:48 INFO - Mirror: Off 19:47:48 INFO - Online: Yes 19:47:48 INFO - Rotation: Supported 19:47:48 INFO - Return code: 0 19:47:48 INFO - Running post-action listener: _resource_record_post_action 19:47:48 INFO - ##### 19:47:48 INFO - ##### Finished run-tests step (success) 19:47:48 INFO - ##### 19:47:48 INFO - Running post-run listener: _resource_record_post_run 19:47:48 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 32755200; Write bytes: 697451008; Read time: 325; Write time: 2052 19:47:48 INFO - TinderboxPrint: CPU usage
53.8% 19:47:48 INFO - TinderboxPrint: I/O read bytes / time
32,755,200 / 325 19:47:48 INFO - TinderboxPrint: I/O write bytes / time
697,451,008 / 2,052 19:47:48 INFO - TinderboxPrint: CPU idle
2,238.1 (46.2%) 19:47:48 INFO - TinderboxPrint: CPU system
968.2 (20.0%) 19:47:48 INFO - TinderboxPrint: CPU user
1,639.3 (33.8%) 19:47:48 INFO - TinderboxPrint: Swap in / out
428,912,640 / 0 19:47:48 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 394123776; Write bytes: 387995648; Read time: 29604; Write time: 763 19:47:48 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 30961152; Write bytes: 293988864; Read time: 305; Write time: 1249 19:47:48 INFO - Running post-run listener: _upload_blobber_files 19:47:48 INFO - Blob upload gear active. 19:47:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:47:48 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 19:47:48 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:47:48 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:47:49 INFO - (blobuploader) - INFO - Open directory for files ... 19:47:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:47:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:47:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:47:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:47:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:47:50 INFO - (blobuploader) - INFO - Done attempting. 19:47:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:47:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:47:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:47:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:47:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:47:51 INFO - (blobuploader) - INFO - Done attempting. 19:47:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:47:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:47:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:47:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:47:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:47:53 INFO - (blobuploader) - INFO - Done attempting. 19:47:53 INFO - (blobuploader) - INFO - Iteration through files over. 19:47:53 INFO - Return code: 0 19:47:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:47:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:47:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/2ebaf08f2f8d63e9bc5a921daa85315915c84e56e858ab3b24361e0f673cc9c9e12c6a165618b079c28a58c1ad6b7d11a968e839ec5b34c70f3e246804988a07", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/213a7834f47d5da3e556615f3eb24dac817df24b4a316cc0a7aaefab472db5ee5f3bbb40d75147bdc3d728b3f942fa69fae28faa4eed142860fd983ca73aeb0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/84c4cf10d939c23c223e38477efe5c5c83ac82f206b09afb54c9fb5fd15c40da2a2567fed39213ecf5a6ee2a09f1dd002052932302cc52e1133e0804ae7b1629"} 19:47:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:47:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:47:53 INFO - Contents: 19:47:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/2ebaf08f2f8d63e9bc5a921daa85315915c84e56e858ab3b24361e0f673cc9c9e12c6a165618b079c28a58c1ad6b7d11a968e839ec5b34c70f3e246804988a07", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/213a7834f47d5da3e556615f3eb24dac817df24b4a316cc0a7aaefab472db5ee5f3bbb40d75147bdc3d728b3f942fa69fae28faa4eed142860fd983ca73aeb0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/84c4cf10d939c23c223e38477efe5c5c83ac82f206b09afb54c9fb5fd15c40da2a2567fed39213ecf5a6ee2a09f1dd002052932302cc52e1133e0804ae7b1629"} 19:47:53 INFO - Running post-run listener: copy_logs_to_upload_dir 19:47:53 INFO - Copying logs to upload dir... 19:47:53 INFO - mkdir: /builds/slave/test/build/upload/logs 19:47:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1299.707714 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 39 secs) (at 2016-06-01 19:47:53.946987) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 19:47:53.950879) ========= 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.xHCPsN9u09/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.aNgyg6Kdto/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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/Larch/sha512/2ebaf08f2f8d63e9bc5a921daa85315915c84e56e858ab3b24361e0f673cc9c9e12c6a165618b079c28a58c1ad6b7d11a968e839ec5b34c70f3e246804988a07", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/213a7834f47d5da3e556615f3eb24dac817df24b4a316cc0a7aaefab472db5ee5f3bbb40d75147bdc3d728b3f942fa69fae28faa4eed142860fd983ca73aeb0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/84c4cf10d939c23c223e38477efe5c5c83ac82f206b09afb54c9fb5fd15c40da2a2567fed39213ecf5a6ee2a09f1dd002052932302cc52e1133e0804ae7b1629"} build_url:https://queue.taskcluster.net/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011201 build_url: 'https://queue.taskcluster.net/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/2ebaf08f2f8d63e9bc5a921daa85315915c84e56e858ab3b24361e0f673cc9c9e12c6a165618b079c28a58c1ad6b7d11a968e839ec5b34c70f3e246804988a07", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/213a7834f47d5da3e556615f3eb24dac817df24b4a316cc0a7aaefab472db5ee5f3bbb40d75147bdc3d728b3f942fa69fae28faa4eed142860fd983ca73aeb0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/84c4cf10d939c23c223e38477efe5c5c83ac82f206b09afb54c9fb5fd15c40da2a2567fed39213ecf5a6ee2a09f1dd002052932302cc52e1133e0804ae7b1629"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 19:47:53.981986) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 19:47:53.982294) ========= 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.xHCPsN9u09/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.aNgyg6Kdto/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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.005178 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 19:47:54.032007) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 19:47:54.032314) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 19:47:54.032660) ========= ========= Total master_lag: 0.21 =========