builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0220 starttime: 1465352542.37 results: success (0) buildid: 20160607155900 builduid: 6b684ed8fb71452c861d5ccc62893a18 revision: a39da695528a04705d25d0159d6250917c95eee0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-07 19:22:22.368371) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-07 19:22:22.368802) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-07 19:22:22.369102) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-07 19:22:22.392536) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 19:22:22.392895) ========= 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.ch4bkMonA2/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.miUwxOeUCg/Listeners TMPDIR=/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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-07 19:22:22-- 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% 165M=0s 2016-06-07 19:22:22 (165 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.113161 ========= master_lag: 0.20 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 19:22:22.707348) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 19:22:22.707710) ========= 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.ch4bkMonA2/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.miUwxOeUCg/Listeners TMPDIR=/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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.025527 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 19:22:22.754369) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-07 19:22:22.754930) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev a39da695528a04705d25d0159d6250917c95eee0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev a39da695528a04705d25d0159d6250917c95eee0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ch4bkMonA2/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.miUwxOeUCg/Listeners TMPDIR=/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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-07 19:22:22,840 truncating revision to first 12 chars 2016-06-07 19:22:22,840 Setting DEBUG logging. 2016-06-07 19:22:22,840 attempt 1/10 2016-06-07 19:22:22,840 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/a39da695528a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-07 19:22:24,592 unpacking tar archive at: autoland-a39da695528a/testing/mozharness/ program finished with exit code 0 elapsedTime=2.520390 ========= master_lag: 0.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-07 19:22:25.408344) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-07 19:22:25.408715) ========= script_repo_revision: a39da695528a04705d25d0159d6250917c95eee0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-07 19:22:25.409148) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-07 19:22:25.409418) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-07 19:22:25.427579) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-06-07 19:22:25.427964) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ch4bkMonA2/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.miUwxOeUCg/Listeners TMPDIR=/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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:22:25 INFO - MultiFileLogger online at 20160607 19:22:25 in /builds/slave/test 19:22:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 19:22:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:22:25 INFO - {'append_to_log': False, 19:22:25 INFO - 'base_work_dir': '/builds/slave/test', 19:22:25 INFO - 'blob_upload_branch': 'Autoland', 19:22:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:22:25 INFO - 'branch': 'Autoland', 19:22:25 INFO - 'buildbot_json_path': 'buildprops.json', 19:22:25 INFO - 'config_files': ('talos/mac_config.py',), 19:22:25 INFO - 'default_actions': ('clobber', 19:22:25 INFO - 'read-buildbot-config', 19:22:25 INFO - 'download-and-extract', 19:22:25 INFO - 'populate-webroot', 19:22:25 INFO - 'create-virtualenv', 19:22:25 INFO - 'install', 19:22:25 INFO - 'run-tests'), 19:22:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:22:25 INFO - 'download_minidump_stackwalk': True, 19:22:25 INFO - 'download_symbols': 'ondemand', 19:22:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:22:25 INFO - 'tooltool.py': '/tools/tooltool.py', 19:22:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:22:25 INFO - '/tools/misc-python/virtualenv.py')}, 19:22:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:22:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:22:25 INFO - 'installer_path': 'installer.exe', 19:22:25 INFO - 'log_level': 'info', 19:22:25 INFO - 'log_name': 'talos', 19:22:25 INFO - 'log_to_console': True, 19:22:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:22:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:22:25 INFO - 'opt_config_files': (), 19:22:25 INFO - 'pip_index': False, 19:22:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:22:25 INFO - 'cmd': ('bash', 19:22:25 INFO - '-c', 19:22:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:22:25 INFO - 'enabled': True, 19:22:25 INFO - 'halt_on_failure': False, 19:22:25 INFO - 'name': 'check_screen_resolution'},), 19:22:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:22:25 INFO - 'cmd': ('bash', 19:22:25 INFO - '-c', 19:22:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:22:25 INFO - 'enabled': True, 19:22:25 INFO - 'halt_on_failure': False, 19:22:25 INFO - 'name': 'check_screen_resolution'},), 19:22:25 INFO - 'run_cmd_checks_enabled': True, 19:22:25 INFO - 'sps_profile': False, 19:22:25 INFO - 'sps_profile_interval': 0, 19:22:25 INFO - 'suite': 'dromaeojs', 19:22:25 INFO - 'system_bits': '32', 19:22:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:22:25 INFO - 'title': 't-yosemite-r7-0220', 19:22:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:22:25 INFO - 'use_talos_json': True, 19:22:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:22:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:22:25 INFO - 'work_dir': 'build'} 19:22:25 INFO - ##### 19:22:25 INFO - ##### Running clobber step. 19:22:25 INFO - ##### 19:22:25 INFO - Running pre-action listener: _resource_record_pre_action 19:22:25 INFO - Running main action method: clobber 19:22:25 INFO - rmtree: /builds/slave/test/build 19:22:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:22:27 INFO - Running post-action listener: _resource_record_post_action 19:22:27 INFO - ##### 19:22:27 INFO - ##### Finished clobber step (success) 19:22:27 INFO - ##### 19:22:27 INFO - ##### 19:22:27 INFO - ##### Running read-buildbot-config step. 19:22:27 INFO - ##### 19:22:27 INFO - Running pre-action listener: _resource_record_pre_action 19:22:27 INFO - Running main action method: read_buildbot_config 19:22:27 INFO - Using buildbot properties: 19:22:27 INFO - { 19:22:27 INFO - "project": "", 19:22:27 INFO - "product": "firefox", 19:22:27 INFO - "script_repo_revision": "production", 19:22:27 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 19:22:27 INFO - "repository": "", 19:22:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 19:22:27 INFO - "buildid": "20160607155900", 19:22:27 INFO - "pgo_build": "False", 19:22:27 INFO - "basedir": "/builds/slave/test", 19:22:27 INFO - "buildnumber": 0, 19:22:27 INFO - "slavename": "t-yosemite-r7-0220", 19:22:27 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 19:22:27 INFO - "platform": "yosemite_r7", 19:22:27 INFO - "scheduler": "tests-autoland-macosx64-talos", 19:22:27 INFO - "branch": "autoland", 19:22:27 INFO - "repo_path": "integration/autoland", 19:22:27 INFO - "revision": "a39da695528a04705d25d0159d6250917c95eee0", 19:22:27 INFO - "stage_platform": "macosx64", 19:22:27 INFO - "builduid": "6b684ed8fb71452c861d5ccc62893a18", 19:22:27 INFO - "slavebuilddir": "test" 19:22:27 INFO - } 19:22:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/Z_vZY1aJSVyoyiK2em2GUw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 19:22:27 INFO - Running post-action listener: _resource_record_post_action 19:22:27 INFO - ##### 19:22:27 INFO - ##### Finished read-buildbot-config step (success) 19:22:27 INFO - ##### 19:22:27 INFO - ##### 19:22:27 INFO - ##### Running download-and-extract step. 19:22:27 INFO - ##### 19:22:27 INFO - Running pre-action listener: _resource_record_pre_action 19:22:27 INFO - Running main action method: download_and_extract 19:22:27 INFO - mkdir: /builds/slave/test/build/tests 19:22: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:22:27 INFO - https://queue.taskcluster.net/v1/task/Z_vZY1aJSVyoyiK2em2GUw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 19:22:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_vZY1aJSVyoyiK2em2GUw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 19:22:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_vZY1aJSVyoyiK2em2GUw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 19:22:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_vZY1aJSVyoyiK2em2GUw/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:22:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_vZY1aJSVyoyiK2em2GUw/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:22:32 INFO - Downloaded 1199 bytes. 19:22:32 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 19:22:32 INFO - Using the following test package requirements: 19:22:32 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 19:22:32 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:22:32 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 19:22:32 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:22:32 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 19:22:32 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:22:32 INFO - u'jsshell-mac.zip'], 19:22:32 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:22:32 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 19:22:32 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 19:22:32 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:22:32 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 19:22:32 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:22:32 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 19:22:32 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:22:32 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 19:22:32 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:22:32 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 19:22:32 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:22:32 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:22:32 INFO - https://queue.taskcluster.net/v1/task/Z_vZY1aJSVyoyiK2em2GUw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:22:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_vZY1aJSVyoyiK2em2GUw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:22:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_vZY1aJSVyoyiK2em2GUw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:22:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_vZY1aJSVyoyiK2em2GUw/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:22:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_vZY1aJSVyoyiK2em2GUw/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:22:33 INFO - Downloaded 18697173 bytes. 19:22:33 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:22:33 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:22:36 INFO - Return code: 0 19:22:36 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:22:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:22:36 INFO - https://queue.taskcluster.net/v1/task/Z_vZY1aJSVyoyiK2em2GUw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:22:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_vZY1aJSVyoyiK2em2GUw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:22:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_vZY1aJSVyoyiK2em2GUw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:22:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_vZY1aJSVyoyiK2em2GUw/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:22:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_vZY1aJSVyoyiK2em2GUw/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:22:37 INFO - Downloaded 18697173 bytes. 19:22:37 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:22:37 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:22:41 INFO - Return code: 0 19:22:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:22:41 INFO - https://queue.taskcluster.net/v1/task/Z_vZY1aJSVyoyiK2em2GUw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 19:22:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_vZY1aJSVyoyiK2em2GUw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:22:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_vZY1aJSVyoyiK2em2GUw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:22:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_vZY1aJSVyoyiK2em2GUw/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:22:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_vZY1aJSVyoyiK2em2GUw/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:22:42 INFO - Downloaded 10834055 bytes. 19:22:42 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:22:42 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:22:42 INFO - Return code: 0 19:22:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:22:42 INFO - https://queue.taskcluster.net/v1/task/Z_vZY1aJSVyoyiK2em2GUw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 19:22:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_vZY1aJSVyoyiK2em2GUw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:22:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_vZY1aJSVyoyiK2em2GUw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:22:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_vZY1aJSVyoyiK2em2GUw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 19:22:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_vZY1aJSVyoyiK2em2GUw/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:22:46 INFO - Downloaded 111094562 bytes. 19:22:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Z_vZY1aJSVyoyiK2em2GUw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:22:46 INFO - mkdir: /builds/slave/test/properties 19:22:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:22:46 INFO - Writing to file /builds/slave/test/properties/build_url 19:22:46 INFO - Contents: 19:22:46 INFO - build_url:https://queue.taskcluster.net/v1/task/Z_vZY1aJSVyoyiK2em2GUw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:22:47 INFO - Running post-action listener: _resource_record_post_action 19:22:47 INFO - Running post-action listener: set_extra_try_arguments 19:22:47 INFO - ##### 19:22:47 INFO - ##### Finished download-and-extract step (success) 19:22:47 INFO - ##### 19:22:47 INFO - ##### 19:22:47 INFO - ##### Running populate-webroot step. 19:22:47 INFO - ##### 19:22:47 INFO - Running pre-action listener: _resource_record_pre_action 19:22:47 INFO - Running main action method: populate_webroot 19:22:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:22:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:22:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:22:47 INFO - '--tppagecycles', 19:22:47 INFO - '7'], 19:22:47 INFO - 'tests': ['tsvgm']}}, 19:22:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:22:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:22:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:22:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:22:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:22:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:22:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:22:47 INFO - 'talos_options': ['--disable-e10s'], 19:22:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:22:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:22:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:22:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:22:47 INFO - 'talos_options': ['--disable-e10s'], 19:22:47 INFO - 'tests': ['damp', 'tps']}, 19:22:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:22:47 INFO - 'tests': ['damp', 'tps']}, 19:22:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:22:47 INFO - 'tests': ['dromaeo_dom']}, 19:22:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:22:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:22:47 INFO - 'tests': ['a11yr', 19:22:47 INFO - 'ts_paint', 19:22:47 INFO - 'tpaint', 19:22:47 INFO - 'sessionrestore', 19:22:47 INFO - 'sessionrestore_no_auto_restore', 19:22:47 INFO - 'tabpaint']}, 19:22:47 INFO - 'other-e10s': {'tests': ['a11yr', 19:22:47 INFO - 'ts_paint', 19:22:47 INFO - 'tpaint', 19:22:47 INFO - 'sessionrestore', 19:22:47 INFO - 'sessionrestore_no_auto_restore', 19:22:47 INFO - 'tabpaint']}, 19:22:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:22:47 INFO - 'ts_paint', 19:22:47 INFO - 'tpaint', 19:22:47 INFO - 'sessionrestore', 19:22:47 INFO - 'sessionrestore_no_auto_restore', 19:22:47 INFO - 'tabpaint']}, 19:22:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:22:47 INFO - 'ts_paint', 19:22:47 INFO - 'tpaint', 19:22:47 INFO - 'sessionrestore', 19:22:47 INFO - 'sessionrestore_no_auto_restore', 19:22:47 INFO - 'tabpaint']}, 19:22:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:22:47 INFO - 'tests': ['a11yr', 19:22:47 INFO - 'ts_paint', 19:22:47 INFO - 'tpaint', 19:22:47 INFO - 'sessionrestore', 19:22:47 INFO - 'sessionrestore_no_auto_restore', 19:22:47 INFO - 'tabpaint']}, 19:22:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:22:47 INFO - 'tests': ['a11yr', 19:22:47 INFO - 'ts_paint', 19:22:47 INFO - 'tpaint', 19:22:47 INFO - 'sessionrestore', 19:22:47 INFO - 'sessionrestore_no_auto_restore', 19:22:47 INFO - 'tabpaint']}, 19:22:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:22:47 INFO - 'tests': ['tsvgx', 19:22:47 INFO - 'tsvgr_opacity', 19:22:47 INFO - 'tart', 19:22:47 INFO - 'tscrollx', 19:22:47 INFO - 'cart']}, 19:22:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:22:47 INFO - 'tsvgr_opacity', 19:22:47 INFO - 'tart', 19:22:47 INFO - 'tscrollx', 19:22:47 INFO - 'cart']}, 19:22:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:22:47 INFO - 'talos_options': ['--disable-e10s'], 19:22:47 INFO - 'tests': ['tp5o']}, 19:22:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:22:47 INFO - 'tests': ['tp5o']}, 19:22:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:22:47 INFO - 'talos_options': ['--disable-e10s', 19:22:47 INFO - '--xperf_path', 19:22:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:22:47 INFO - 'tests': ['tp5n']}, 19:22:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:22:47 INFO - 'talos_options': ['--xperf_path', 19:22:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:22:47 INFO - 'tests': ['tp5n']}}, 19:22:47 INFO - 'talos.zip': {'path': '', 19:22:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:22:47 INFO - Running post-action listener: _resource_record_post_action 19:22:47 INFO - ##### 19:22:47 INFO - ##### Finished populate-webroot step (success) 19:22:47 INFO - ##### 19:22:47 INFO - ##### 19:22:47 INFO - ##### Running create-virtualenv step. 19:22:47 INFO - ##### 19:22:47 INFO - Running pre-action listener: _resource_record_pre_action 19:22:47 INFO - Running main action method: create_virtualenv 19:22:47 INFO - Creating virtualenv /builds/slave/test/build/venv 19:22:47 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:22:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:22:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:22:47 INFO - Using real prefix '/tools/python27' 19:22:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:22:48 INFO - Installing distribute.............................................................................................................................................................................................done. 19:22:51 INFO - Installing pip.................done. 19:22:51 INFO - Return code: 0 19:22:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:22:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:22:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:22:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:51 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 0x106b12a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b18570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2ba495d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069b6d30>, '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 0x1067dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069adb20>, '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.ch4bkMonA2/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.miUwxOeUCg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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:22:51 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:22:51 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:22:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ch4bkMonA2/Render', 19:22:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:51 INFO - 'HOME': '/Users/cltbld', 19:22:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:51 INFO - 'LOGNAME': 'cltbld', 19:22:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:51 INFO - 'MOZ_NO_REMOTE': '1', 19:22:51 INFO - 'NO_EM_RESTART': '1', 19:22:51 INFO - 'PAGER': '/bin/cat', 19:22:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:51 INFO - 'PWD': '/builds/slave/test', 19:22:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:51 INFO - 'SHELL': '/bin/bash', 19:22:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.miUwxOeUCg/Listeners', 19:22:51 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 19:22:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:51 INFO - 'USER': 'cltbld', 19:22:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:51 INFO - 'XPC_FLAGS': '0x0', 19:22:51 INFO - 'XPC_SERVICE_NAME': '0', 19:22:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:51 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:22:51 INFO - Cleaning up... 19:22:51 INFO - Return code: 0 19:22:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:22:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:22:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:22:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:51 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 0x106b12a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b18570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2ba495d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069b6d30>, '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 0x1067dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069adb20>, '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.ch4bkMonA2/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.miUwxOeUCg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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:22:51 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:22:51 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:22:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ch4bkMonA2/Render', 19:22:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:51 INFO - 'HOME': '/Users/cltbld', 19:22:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:51 INFO - 'LOGNAME': 'cltbld', 19:22:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:51 INFO - 'MOZ_NO_REMOTE': '1', 19:22:51 INFO - 'NO_EM_RESTART': '1', 19:22:51 INFO - 'PAGER': '/bin/cat', 19:22:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:51 INFO - 'PWD': '/builds/slave/test', 19:22:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:51 INFO - 'SHELL': '/bin/bash', 19:22:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.miUwxOeUCg/Listeners', 19:22:51 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 19:22:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:51 INFO - 'USER': 'cltbld', 19:22:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:51 INFO - 'XPC_FLAGS': '0x0', 19:22:51 INFO - 'XPC_SERVICE_NAME': '0', 19:22:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:51 INFO - Downloading/unpacking psutil>=3.1.1 19:22:51 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:22:51 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:22:51 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:22:51 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:22:51 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:22:51 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:22:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:22:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:22:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:22:54 INFO - Installing collected packages: psutil 19:22:54 INFO - Running setup.py install for psutil 19:22:54 INFO - building 'psutil._psutil_osx' extension 19:22:54 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:22:55 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:22:55 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:22:55 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:22:55 INFO - building 'psutil._psutil_posix' extension 19:22:55 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:22:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:22:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:22:55 INFO - ^ 19:22:55 INFO - 1 warning generated. 19:22:55 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:22:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:22:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:22:55 INFO - Successfully installed psutil 19:22:55 INFO - Cleaning up... 19:22:56 INFO - Return code: 0 19:22:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:22:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:22:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:22:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106b12a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b18570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2ba495d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069b6d30>, '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 0x1067dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069adb20>, '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.ch4bkMonA2/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.miUwxOeUCg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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:22:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:22:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:22:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ch4bkMonA2/Render', 19:22:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:56 INFO - 'HOME': '/Users/cltbld', 19:22:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:56 INFO - 'LOGNAME': 'cltbld', 19:22:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:56 INFO - 'MOZ_NO_REMOTE': '1', 19:22:56 INFO - 'NO_EM_RESTART': '1', 19:22:56 INFO - 'PAGER': '/bin/cat', 19:22:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:56 INFO - 'PWD': '/builds/slave/test', 19:22:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:56 INFO - 'SHELL': '/bin/bash', 19:22:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.miUwxOeUCg/Listeners', 19:22:56 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 19:22:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:56 INFO - 'USER': 'cltbld', 19:22:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:56 INFO - 'XPC_FLAGS': '0x0', 19:22:56 INFO - 'XPC_SERVICE_NAME': '0', 19:22:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:22:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:22:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:22:58 INFO - Installing collected packages: mozsystemmonitor 19:22:58 INFO - Running setup.py install for mozsystemmonitor 19:22:58 INFO - Successfully installed mozsystemmonitor 19:22:58 INFO - Cleaning up... 19:22:59 INFO - Return code: 0 19:22:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:22:59 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:22:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:59 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:22:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:59 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 0x106b12a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b18570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2ba495d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069b6d30>, '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 0x1067dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069adb20>, '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.ch4bkMonA2/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.miUwxOeUCg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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:22:59 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:22:59 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:22:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ch4bkMonA2/Render', 19:22:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:59 INFO - 'HOME': '/Users/cltbld', 19:22:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:59 INFO - 'LOGNAME': 'cltbld', 19:22:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:59 INFO - 'MOZ_NO_REMOTE': '1', 19:22:59 INFO - 'NO_EM_RESTART': '1', 19:22:59 INFO - 'PAGER': '/bin/cat', 19:22:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:59 INFO - 'PWD': '/builds/slave/test', 19:22:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:59 INFO - 'SHELL': '/bin/bash', 19:22:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.miUwxOeUCg/Listeners', 19:22:59 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 19:22:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:59 INFO - 'USER': 'cltbld', 19:22:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:59 INFO - 'XPC_FLAGS': '0x0', 19:22:59 INFO - 'XPC_SERVICE_NAME': '0', 19:22:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:59 INFO - Downloading/unpacking blobuploader==1.2.4 19:22:59 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:22:59 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:22:59 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:22:59 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:22:59 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:22:59 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:23:01 INFO - Downloading blobuploader-1.2.4.tar.gz 19:23:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:23:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:23:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:23:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:23:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:23:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:23:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:23:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:23:02 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:23:02 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:23:02 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:23:02 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:23:02 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:23:02 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:23:02 INFO - Downloading docopt-0.6.1.tar.gz 19:23:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:23:02 INFO - Installing collected packages: blobuploader, requests, docopt 19:23:02 INFO - Running setup.py install for blobuploader 19:23:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:23:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:23:03 INFO - Running setup.py install for requests 19:23:03 INFO - Running setup.py install for docopt 19:23:03 INFO - Successfully installed blobuploader requests docopt 19:23:03 INFO - Cleaning up... 19:23:03 INFO - Return code: 0 19:23:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:23:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:23:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:23:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:03 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 0x106b12a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b18570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2ba495d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069b6d30>, '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 0x1067dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069adb20>, '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.ch4bkMonA2/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.miUwxOeUCg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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:23:03 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:23:03 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:23:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ch4bkMonA2/Render', 19:23:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:23:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:23:03 INFO - 'HOME': '/Users/cltbld', 19:23:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:23:03 INFO - 'LOGNAME': 'cltbld', 19:23:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:03 INFO - 'MOZ_NO_REMOTE': '1', 19:23:03 INFO - 'NO_EM_RESTART': '1', 19:23:03 INFO - 'PAGER': '/bin/cat', 19:23:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:23:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:03 INFO - 'PWD': '/builds/slave/test', 19:23:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:23:03 INFO - 'SHELL': '/bin/bash', 19:23:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.miUwxOeUCg/Listeners', 19:23:03 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 19:23:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:23:03 INFO - 'USER': 'cltbld', 19:23:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:23:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:23:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:03 INFO - 'XPC_FLAGS': '0x0', 19:23:03 INFO - 'XPC_SERVICE_NAME': '0', 19:23:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:23:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:23:04 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-gNflLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:23:04 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-AgNqxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:23:04 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-2Kwa6g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:23:04 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-tRwBA4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:23:04 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-cWyY8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:23:04 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-aEJPKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:23:04 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-HJiJJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:23:04 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-A1UAjD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:23:04 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-QNXQYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:23:05 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-B1m55f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:23:05 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-TEfNgH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:23:05 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-a19Met-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:23:05 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-cZoDuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:23:05 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-gpQA0O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:23:05 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-YqFJst-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:23:05 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-gXp_UH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:23:05 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-YlqwgR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:23:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:23:05 INFO - Running setup.py install for manifestparser 19:23:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:23:05 INFO - Running setup.py install for mozcrash 19:23:06 INFO - Running setup.py install for mozdebug 19:23:06 INFO - Running setup.py install for mozdevice 19:23:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:23:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:23:06 INFO - Running setup.py install for mozfile 19:23:06 INFO - Running setup.py install for mozhttpd 19:23:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:23:06 INFO - Running setup.py install for mozinfo 19:23:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:23:06 INFO - Running setup.py install for mozInstall 19:23:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:23:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:23:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:23:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:23:06 INFO - Running setup.py install for mozleak 19:23:07 INFO - Running setup.py install for mozlog 19:23:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:23:07 INFO - Running setup.py install for moznetwork 19:23:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:23:07 INFO - Running setup.py install for mozprocess 19:23:07 INFO - Running setup.py install for mozprofile 19:23:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:23:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:23:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:23:07 INFO - Running setup.py install for mozrunner 19:23:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:23:07 INFO - Running setup.py install for mozscreenshot 19:23:08 INFO - Running setup.py install for moztest 19:23:08 INFO - Running setup.py install for mozversion 19:23:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:23:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:23:08 INFO - Cleaning up... 19:23:08 INFO - Return code: 0 19:23:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:23:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:23:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:08 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 0x106b12a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b18570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2ba495d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069b6d30>, '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 0x1067dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069adb20>, '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.ch4bkMonA2/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.miUwxOeUCg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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:23:08 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:23:08 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:23:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ch4bkMonA2/Render', 19:23:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:23:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:23:08 INFO - 'HOME': '/Users/cltbld', 19:23:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:23:08 INFO - 'LOGNAME': 'cltbld', 19:23:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:08 INFO - 'MOZ_NO_REMOTE': '1', 19:23:08 INFO - 'NO_EM_RESTART': '1', 19:23:08 INFO - 'PAGER': '/bin/cat', 19:23:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:23:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:08 INFO - 'PWD': '/builds/slave/test', 19:23:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:23:08 INFO - 'SHELL': '/bin/bash', 19:23:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.miUwxOeUCg/Listeners', 19:23:08 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 19:23:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:23:08 INFO - 'USER': 'cltbld', 19:23:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:23:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:23:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:08 INFO - 'XPC_FLAGS': '0x0', 19:23:08 INFO - 'XPC_SERVICE_NAME': '0', 19:23:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:23:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:23:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:23:08 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-6SWC59-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:23:08 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:23:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:23:08 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-bePKy3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:23:08 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:23:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:23:08 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-Tg6RWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:23:08 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:23:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:23:09 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-aF4vAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:23:09 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:23:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:23:09 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-WDOi__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:23:09 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:23:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:23:09 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-bcDxRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:23:09 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:23:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:23:09 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-YjQNvu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:23:09 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:23:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:23:09 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-qYmyU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:23:09 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:23:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:23:09 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-GNRIqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:23:09 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:23:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:23:09 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-VI7hZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:23:09 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:23:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:23:09 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-IdBZty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:23:09 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:23:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:23:09 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-DjMS_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:23:09 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:23:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:23:09 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-4aop09-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:23:10 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:23:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:23:10 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-GuceZ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:23:10 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:23:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:23:10 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-6ngEMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:23:10 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:23:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:23:10 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-7Rmzk6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:23:10 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:23:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:23:10 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-kqpc15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:23:10 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:23:10 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:23:10 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:23:10 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:23:10 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:23:10 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:23:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:23: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:23: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:23: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:23: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:23: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:23: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:23:12 INFO - Downloading blessings-1.6.tar.gz 19:23:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:23:13 INFO - Installing collected packages: blessings 19:23:13 INFO - Running setup.py install for blessings 19:23:13 INFO - Successfully installed blessings 19:23:13 INFO - Cleaning up... 19:23:13 INFO - Return code: 0 19:23:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:23:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:23:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:23:13 INFO - Reading from file tmpfile_stdout 19:23:13 INFO - Current package versions: 19:23:13 INFO - blessings == 1.6 19:23:13 INFO - blobuploader == 1.2.4 19:23:13 INFO - docopt == 0.6.1 19:23:13 INFO - manifestparser == 1.1 19:23:13 INFO - mozInstall == 1.12 19:23:13 INFO - mozcrash == 0.17 19:23:13 INFO - mozdebug == 0.1 19:23:13 INFO - mozdevice == 0.48 19:23:13 INFO - mozfile == 1.2 19:23:13 INFO - mozhttpd == 0.7 19:23:13 INFO - mozinfo == 0.9 19:23:13 INFO - mozleak == 0.1 19:23:13 INFO - mozlog == 3.2 19:23:13 INFO - moznetwork == 0.27 19:23:13 INFO - mozprocess == 0.22 19:23:13 INFO - mozprofile == 0.28 19:23:13 INFO - mozrunner == 6.11 19:23:13 INFO - mozscreenshot == 0.1 19:23:13 INFO - mozsystemmonitor == 0.3 19:23:13 INFO - moztest == 0.7 19:23:13 INFO - mozversion == 1.4 19:23:13 INFO - psutil == 3.1.1 19:23:13 INFO - requests == 1.2.3 19:23:13 INFO - wsgiref == 0.1.2 19:23:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:23:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:23:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:13 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 0x106b12a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b18570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2ba495d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069b6d30>, '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 0x1067dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069adb20>, '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.ch4bkMonA2/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.miUwxOeUCg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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:23:13 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:23:13 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:23:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ch4bkMonA2/Render', 19:23:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:23:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:23:13 INFO - 'HOME': '/Users/cltbld', 19:23:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:23:13 INFO - 'LOGNAME': 'cltbld', 19:23:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:13 INFO - 'MOZ_NO_REMOTE': '1', 19:23:13 INFO - 'NO_EM_RESTART': '1', 19:23:13 INFO - 'PAGER': '/bin/cat', 19:23:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:23:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:13 INFO - 'PWD': '/builds/slave/test', 19:23:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:23:13 INFO - 'SHELL': '/bin/bash', 19:23:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.miUwxOeUCg/Listeners', 19:23:13 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 19:23:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:23:13 INFO - 'USER': 'cltbld', 19:23:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:23:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:23:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:13 INFO - 'XPC_FLAGS': '0x0', 19:23:13 INFO - 'XPC_SERVICE_NAME': '0', 19:23:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:23:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:23:13 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:23:13 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:23:13 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:23:13 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:23:13 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:23:13 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:23:13 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:23:13 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:23:13 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:23:13 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:23:13 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:23:13 INFO - Cleaning up... 19:23:13 INFO - Return code: 0 19:23:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:23:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:23:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:13 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 0x106b12a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b18570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2ba495d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069b6d30>, '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 0x1067dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069adb20>, '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.ch4bkMonA2/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.miUwxOeUCg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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:23:13 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:23:13 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:23:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ch4bkMonA2/Render', 19:23:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:23:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:23:13 INFO - 'HOME': '/Users/cltbld', 19:23:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:23:13 INFO - 'LOGNAME': 'cltbld', 19:23:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:13 INFO - 'MOZ_NO_REMOTE': '1', 19:23:13 INFO - 'NO_EM_RESTART': '1', 19:23:13 INFO - 'PAGER': '/bin/cat', 19:23:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:23:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:13 INFO - 'PWD': '/builds/slave/test', 19:23:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:23:13 INFO - 'SHELL': '/bin/bash', 19:23:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.miUwxOeUCg/Listeners', 19:23:13 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 19:23:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:23:13 INFO - 'USER': 'cltbld', 19:23:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:23:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:23:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:13 INFO - 'XPC_FLAGS': '0x0', 19:23:13 INFO - 'XPC_SERVICE_NAME': '0', 19:23:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:23:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:23:14 INFO - Downloading/unpacking jsonschema 19:23:14 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:23:14 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:23:14 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:23:14 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:23:14 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:23:14 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:23:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:23:16 INFO - Downloading/unpacking functools32 (from jsonschema) 19:23: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:23: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:23: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:23: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:23: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:23: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:23:16 INFO - Downloading functools32-3.2.3-2.tar.gz 19:23:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:23:16 INFO - warning: no files found matching '*.txt' 19:23:16 INFO - no previously-included directories found matching 'build' 19:23:16 INFO - no previously-included directories found matching 'dist' 19:23:16 INFO - no previously-included directories found matching '.git*' 19:23:16 INFO - Installing collected packages: jsonschema, functools32 19:23:17 INFO - Running setup.py install for functools32 19:23:17 INFO - warning: no files found matching '*.txt' 19:23:17 INFO - no previously-included directories found matching 'build' 19:23:17 INFO - no previously-included directories found matching 'dist' 19:23:17 INFO - no previously-included directories found matching '.git*' 19:23:17 INFO - Successfully installed jsonschema functools32 19:23:17 INFO - Cleaning up... 19:23:17 INFO - Return code: 0 19:23:17 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:23: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:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23: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:23:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:17 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 0x106b12a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b18570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2ba495d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069b6d30>, '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 0x1067dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069adb20>, '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.ch4bkMonA2/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.miUwxOeUCg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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:23:17 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:23:17 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:23:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ch4bkMonA2/Render', 19:23:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:23:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:23:17 INFO - 'HOME': '/Users/cltbld', 19:23:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:23:17 INFO - 'LOGNAME': 'cltbld', 19:23:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:17 INFO - 'MOZ_NO_REMOTE': '1', 19:23:17 INFO - 'NO_EM_RESTART': '1', 19:23:17 INFO - 'PAGER': '/bin/cat', 19:23:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:23:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:17 INFO - 'PWD': '/builds/slave/test', 19:23:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:23:17 INFO - 'SHELL': '/bin/bash', 19:23:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.miUwxOeUCg/Listeners', 19:23:17 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 19:23:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:23:17 INFO - 'USER': 'cltbld', 19:23:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:23:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:23:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:17 INFO - 'XPC_FLAGS': '0x0', 19:23:17 INFO - 'XPC_SERVICE_NAME': '0', 19:23:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:23:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:23:17 INFO - Downloading/unpacking flake8 19:23:17 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:23:17 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:23:17 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:23:17 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:23:17 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:23:17 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:23:19 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:23:19 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:23:19 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:23:19 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:23:19 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:23:19 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:23:19 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:23:19 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:23:20 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:23:20 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:23:20 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:23:20 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:23:20 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:23:20 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:23:20 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:23:20 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:23:20 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:23:20 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:23:20 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:23:20 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:23:20 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:23:20 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:23:20 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:23:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:23:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:23:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:23:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:23:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:23:21 INFO - no previously-included directories found matching 'docs/_build' 19:23:21 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:23:21 INFO - Running setup.py install for pep8 19:23:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:23:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:23:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:23:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:23:21 INFO - no previously-included directories found matching 'docs/_build' 19:23:21 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:23:21 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:23:21 INFO - Cleaning up... 19:23:21 INFO - Return code: 0 19:23:21 INFO - Running post-action listener: _resource_record_post_action 19:23:21 INFO - Running post-action listener: _start_resource_monitoring 19:23:21 INFO - Starting resource monitoring. 19:23:21 INFO - ##### 19:23:21 INFO - ##### Finished create-virtualenv step (success) 19:23:21 INFO - ##### 19:23:21 INFO - ##### 19:23:21 INFO - ##### Running install step. 19:23:21 INFO - ##### 19:23:21 INFO - Running pre-action listener: _resource_record_pre_action 19:23:21 INFO - Running main action method: install 19:23:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:23:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:23:22 INFO - Reading from file tmpfile_stdout 19:23:22 INFO - Detecting whether we're running mozinstall >=1.0... 19:23:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:23:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:23:22 INFO - Reading from file tmpfile_stdout 19:23:22 INFO - Output received: 19:23:22 INFO - Usage: mozinstall [options] installer 19:23:22 INFO - Options: 19:23:22 INFO - -h, --help show this help message and exit 19:23:22 INFO - -d DEST, --destination=DEST 19:23:22 INFO - Directory to install application into. [default: 19:23:22 INFO - "/builds/slave/test"] 19:23:22 INFO - --app=APP Application being installed. [default: firefox] 19:23:22 INFO - mkdir: /builds/slave/test/build/application 19:23:22 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:23:22 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:23:55 INFO - Reading from file tmpfile_stdout 19:23:55 INFO - Output received: 19:23:55 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:23:55 INFO - Running post-action listener: _resource_record_post_action 19:23:55 INFO - ##### 19:23:55 INFO - ##### Finished install step (success) 19:23:55 INFO - ##### 19:23:55 INFO - ##### 19:23:55 INFO - ##### Running run-tests step. 19:23:55 INFO - ##### 19:23:55 INFO - Running pre-action listener: _resource_record_pre_action 19:23:55 INFO - Running main action method: run_tests 19:23:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:23:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:23:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:23:55 INFO - 2016-06-07 19:23:55.738 screenresolution[1643:7732] starting screenresolution argv=screenresolution get 19:23:55 INFO - 2016-06-07 19:23:55.740 screenresolution[1643:7732] Display 0: 1600x1200x32@60 19:23:55 INFO - 2016-06-07 19:23:55.747 screenresolution[1644:7735] starting screenresolution argv=screenresolution list 19:23:55 INFO - Available Modes on Display 0 19:23:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:23:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:23:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:23:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:23:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:23:55 INFO - Intel Iris: 19:23:55 INFO - Chipset Model: Intel Iris 19:23:55 INFO - Type: GPU 19:23:55 INFO - Bus: Built-In 19:23:55 INFO - VRAM (Dynamic, Max): 1536 MB 19:23:55 INFO - Vendor: Intel (0x8086) 19:23:55 INFO - Device ID: 0x0a2e 19:23:55 INFO - Revision ID: 0x0009 19:23:55 INFO - Displays: 19:23:55 INFO - Display: 19:23:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:23:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:23:55 INFO - Display Serial Number: 1600x1200 60 19:23:55 INFO - Main Display: Yes 19:23:55 INFO - Mirror: Off 19:23:55 INFO - Online: Yes 19:23:55 INFO - Rotation: Supported 19:23:55 INFO - Return code: 0 19:23:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:23:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:23:55 INFO - Python 2.7.3 19:23:55 INFO - Return code: 0 19:23:55 INFO - grabbing minidump binary from tooltool 19:23: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:23:55 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069b6d30>, '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 0x1067dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069adb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:23:55 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 19:23:55 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 19:23:55 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 19:23:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:23:56 INFO - Return code: 0 19:23:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:23:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:23:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:23:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:23:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:23:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:23:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:23:57 INFO - Return code: 0 19:23:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z_vZY1aJSVyoyiK2em2GUw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0220', '--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:23:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Z_vZY1aJSVyoyiK2em2GUw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0220 --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:23:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ch4bkMonA2/Render', 19:23:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:23:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:23:57 INFO - 'HOME': '/Users/cltbld', 19:23:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:23:57 INFO - 'LOGNAME': 'cltbld', 19:23:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:23:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:23:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:57 INFO - 'MOZ_NO_REMOTE': '1', 19:23:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:23:57 INFO - 'NO_EM_RESTART': '1', 19:23:57 INFO - 'PAGER': '/bin/cat', 19:23:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:23:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:57 INFO - 'PWD': '/builds/slave/test', 19:23:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:23:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:23:57 INFO - 'SHELL': '/bin/bash', 19:23:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.miUwxOeUCg/Listeners', 19:23:57 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 19:23:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:23:57 INFO - 'USER': 'cltbld', 19:23:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:23:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:23:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:57 INFO - 'XPC_FLAGS': '0x0', 19:23:57 INFO - 'XPC_SERVICE_NAME': '0', 19:23:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:23:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z_vZY1aJSVyoyiK2em2GUw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0220', '--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:23:57 INFO - mozversion application_buildid: 20160607155900 19:23:57 INFO - mozversion application_changeset: a39da695528a04705d25d0159d6250917c95eee0 19:23:57 INFO - mozversion application_display_name: Nightly 19:23:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:23:57 INFO - mozversion application_name: Firefox 19:23:57 INFO - mozversion application_remotingname: firefox 19:23:57 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 19:23:57 INFO - mozversion application_vendor: Mozilla 19:23:57 INFO - mozversion application_version: 49.0a1 19:23:57 INFO - mozversion platform_buildid: 20160607155900 19:23:57 INFO - mozversion platform_changeset: a39da695528a04705d25d0159d6250917c95eee0 19:23:57 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 19:23:57 INFO - mozversion platform_version: 49.0a1 19:23:57 INFO - using testdate: 1465352637 19:23:57 INFO - actual date: 1465352637 19:23:57 INFO - starting webserver on 'localhost:49278' 19:23:57 INFO - SUITE-START | Running 2 tests 19:23:57 INFO - TEST-START | dromaeo_css 19:23:57 INFO - operating with platform_type : mac_ 19:23:57 INFO - Initialising browser for dromaeo_css test... 19:23:57 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/tmp_xQfcd/profile http://localhost:49278/getInfo.html) 19:23:59 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 19:23:59 INFO - PROCESS | 1662 | colorDepth:24 19:23:59 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 19:23:59 INFO - PROCESS | 1662 | __metrics 19:23:59 INFO - PROCESS | 1662 | 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:23:59 ERROR - PROCESS | 1662 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:24:00 INFO - TEST-INFO | 1662: exit 0 19:24:00 INFO - Browser initialized. 19:24:00 INFO - Running cycle 1/1 for dromaeo_css test... 19:24:00 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/tmp_xQfcd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:24:12 INFO - PROCESS | 1664 | RSS: Main: 242442240 19:24:12 INFO - PROCESS | 1664 | 19:25:04 INFO - PROCESS | 1664 | 1465352704259 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:27:15 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 19:27:16 INFO - PROCESS | 1664 | RSS: Main: 381882368 19:27:16 INFO - PROCESS | 1664 | 19:30:19 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 19:30:20 INFO - PROCESS | 1664 | RSS: Main: 383606784 19:30:20 INFO - PROCESS | 1664 | 19:33:22 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 19:33:23 INFO - PROCESS | 1664 | RSS: Main: 376041472 19:33:23 INFO - PROCESS | 1664 | 19:36:25 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 19:36:26 INFO - PROCESS | 1664 | RSS: Main: 375324672 19:36:26 INFO - PROCESS | 1664 | 19:39:29 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 19:39:30 INFO - PROCESS | 1664 | RSS: Main: 342048768 19:39:30 INFO - PROCESS | 1664 | 19:42:33 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 19:42:33 INFO - PROCESS | 1664 | RSS: Main: 404307968 19:42:33 INFO - PROCESS | 1664 | 19:42:33 INFO - PROCESS | 1664 | __start_tp_report 19:42:33 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 19:42:33 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 19:42:33 INFO - PROCESS | 1664 | |i|pagename|runs| 19:42:33 INFO - PROCESS | 1664 | |0;dojo.html;3318.485634726617;3416.2142707177354;3418.589769227693;3441.5353927219817;3495.807730574819;5458.426865179159;5525.475079867411;5548.278723765915;5599.944000559994;5627.240322556454;5571.13647384656;5602;5605.523530499912;5619.7471113799875;5631.746571404286;5057.620678449112;5091.84724458265;5106.948930510703;5118.436971933084;5172.560332371753;5143.382794064699;5180.870478238036;5192.948070519303;5197.636165468419;5204.219367094947;5057.216131499624;5093.490650934914;5099.745012749367;5103.8468845934685;5178.042062218483;4581.015081757439;5141.100307446182;5187.662801917901;5202.401723801736;5204.011237864794;4977.1538838397555;4979.302897594303;5025.974870125626;5122.078025955327;5122.1036318644465;5437.211604317391;5460.726963651801;5499.175123731432;5510.338759348863;5525.778968841241;5797.449242321973;6101.816945491642;6132.908006379907;6133.601315914496;6140.815775526741;5841.591088623798;5966.373530779292;6113.23584551931;6193.535484838655;6247.406496382836;5707.343655479632;5750.0512415450985;5767.625104368202;5769;5773.566982476331;6257.2491301044165;6273.905891411632;6278.968605156945;6283.214598175228;6286.748530058747;5813.476787089182;5878.588498805043;5932.169496270526;6032.758689652453;6099.75600975956;5176.482351764794;6115.388461153938;6152.753889844445;6153.077038444269;6155.630662160284;6082.756689732361;6098.603590766586;6115.755369785159;6116.633002019804;6120.387961203933;6040.546958978094;6063.393660633901;6102.065131056412;6115.877682446326;6141.324454310023;4811.542903424169;4840.927386089212;4863.221884498464;4873.19592267279;4877.731724755102;4344.304911214191;4361.389405483234;4370.890727731832;4387.780610969438;4419.027813880941;5586.469285417878;5598.776048958078;5612.186232996192;5617.185508101385;5672.801451949162;5947.464728174485;6001.519878409804;6055.455009049207;6189.5048396129105;6202.2867370253;5533.446655334435;5540.9168862467095;5551.195076713854;5552.805651802168;5553.111502159635;5237.083510385667;5274.841754747364;5275.472452754694;5276.92084618731;5280.9471905281225;6161.507079433634;6253.812385628438;6286.968565157145;6292.370762923762;6293.118963345044;6214.533909956681;6215.316315205414;6215.96892015537;6218.409251121137;6238.376162383907;6282.654454004891;6311.873762524816;6316.968415157895;6320.399562041599;6323.968380158254;5945.762169513344;5999.280086389661;6024.246969128859;6056.515478761777;6094.908576371269;6016.007358785751;6153.861431366066;6158.344601843255;6174.38256174379;6220.440160385587;5991.310999234969;6006.849828754316;6007.369226231358;6018.548608854266;6023.126646636126;5782.335031471434;5855.9121613176685;5907.793227237026;5930.851728706644;5958.225430693982;5865.354810970875;5880.323762767336;5887.852803679943;5901.085331773582;5910.881782364415;6002.489788368038;6018.759249630141;6019.18740969981;6044.274687037584;6074.210352654127;4958.851234462972;5497.76300332422;5784.7107644618445;5860.062390017577;5885.352611212848 19:42:33 INFO - PROCESS | 1664 | |1;ext.html;506.2178933547667;511.56005834981914;520.864575210445;524.4886235919978;556.1907424697066;30693.693063069364;32136.35727285453;42025.15949681005;43495;43526.56473435264;44498.77750611243;44869.87825304369;44955.77522112393;44988.550114498845;45024.54975450245;9431.905680943188;9685.418874867495;9695.660651877186;9697.981711920253;9706;8534.317254619647;8774;11443.485043173077;11741.236819606742;11770.47032883518;11738.471768770425;11836.05311575072;11859.051275897951;11859.584914527994;11873.287602743863;8532.402731808714;9842.360246583949;11808.822867656992;11819.586314479038;11822.172447928691;8573.914260857437;8683;8695.56522173895;11334;11722.003629691486;41684.166316673494;42168.15663686709;42212.94467638303;42221.788891055345;42301.57698423038;3605.0987253186704;3642.7814331140216;3719.497867787841;3752.1745216052695;3844.384898416268;32591.51112733311;32613.021609351756;32888.835555822305;33319.50020749691;33323.6667633323;3297.0273769237892;3319.751018673609;3326.501024846268;3355.966440335614;3417.8974630761118;397775;398743;399862;399985.9999999971;400751.98496029916;59564.40435595675;60138.699306503186;60411;60479.69760151259;60485;64633.35366646367;64854.67572662106;64868;64913.67543162254;65014;16236.188190590423;16801.66396672084;16830.07434591085;16860.57848553796;16916.830831691525;11756.353400563052;11990.520379184909;12821.423035963218;12991.935040324737;13072.28102454355;16098.356065757238;16366.345346186019;16376;16407.835921640868;16564.6687066258;7676.692932282758;7678.462507624525;7701.768946931601;7708.537487750753;7730.7680769577;927.7309580221795;966.5505539923969;970.7039352997404;1084.978300433987;1146.6846617180174;30510.694893050786;30593.69406305953;30703.8464807679;31177.532337014964;31562.842185788923;1049.2917280835406;1069.2835800014009;1075.6880504653718;1106.446776611694;1115.1413411672966;13618.863811361958;15668.138252396227;15849.35346866424;15914.363425462852;16116.597085072966;568623;649679;667506;668444;670294;60454.39545604488;60667;60692;60722.78554428887;60727.69636151879;1317.743040107189;1425.2090089999942;1443.5597142871525;1467.735807554629;1474.9115053096848;31915.521267181477;32044.83977580097;32072;32484.83757581197;32609.673903260664;50073.74963125161;50400.49599503958;50981.490185097675;51073.744631278096;51129;551287;553663;554971;555040;556415.2179239078;34707;35401;41281.96795080147;42354.72935811807;42365.78817105895;48906.75546622244;49762.25356619581;50245.246321304476;50492.49507505025;50528.49471505385;31522;38668.806655967666;38878.611213887496;40923.795381024094;41463.58536414597;38527.8441646751;39184.60815391924;40032.3995140079;40233.59766402416;41093;38450.807745961094;39343.2131357377;39411.80294098626;39734.00664983399;41422.378664319454;32805;34686.479702803976;35397.46903796497;35716.107097322776;36177.09557260984;22492.325230243117;25681.743182567934;25902.740972590786;26205.475890482467;26504.602430963165 19:42:33 INFO - PROCESS | 1664 | |2;jquery.html;383.28517315207137;384.6518900395142;390.4416684141678;391.09463041557416;414.3660199894161;17276.136193190352;20089.397318080464;20802.687959680617;21129.683054754172;21245.893770531166;18701.625967480675;19138.13878375473;20709.79290207101;21248;21305.25431609894;4333.696641235115;5745.253117094772;5996.310424301196;6004.879902401971;6007.4593286604195;5848.239728835246;5910.202122713422;6035.758569657209;6040.879182416349;6043.274807023164;5783.248177736909;5904.911426328608;5929.673867937262;5967;5980.79067232647;5785.392533783935;5885.941140588604;5889.646621202742;5940.257467816523;5942.970285148546;5011.548960593527;5223.52988231061;5225.033368826779;5225.137852254374;5238.554722848525;25687.7156142194;25891.611625825626;26184.08355707541;26278.474430511473;26485.73514264852;2173.576152650234;2176.81497072749;2308.0306271366067;2372.9871983585276;2529.1021687300927;14738.70522589553;15400.30698618565;15577.90954633176;16020.679586408323;16061.116638584874;1854.9072546372627;1917.5206198450387;1930.7296978423033;1935.8741681790639;1964.2634012245408;87643.56178219132;117663;118602;122687;125650.3717481416;11159.497822598003;11287.604933827277;11319.264247823865;11352.829707554394;11452.885471145182;12392.442340094718;14104.224267665171;14110.929445352913;14128.505502307575;14228.217448040457;7538.283863033003;7926.801829954182;8212.712555060543;8748.256398206096;8753.606087726068;7073.575585464786;7353.522021068614;8809.03100658927;8821.470711757314;8958.641654333753;5652.604317697722;5719.399463056319;6681.632510211888;6843.657817109125;6872.484563657746;1721.8794684371974;1768.2484943898792;1816.6366726654458;1862.9254829806655;1874.9625007500047;979.8677178580942;1021.8109649714827;1041.7133495665282;1043.4209013997154;1043.9008294212038;20892.89553552253;21305.893470532857;21818.672719909213;22414;22429.887850560644;954.117441366733;1001.5543083327995;1033.4367769565604;1041.4324193314662;1138.385271953136;11191;11665.88334116648;11807.23252988553;11855.881441185651;11878.049756019498;592653;600399;601085;601580;611672;72258.2774172262;97976.53035204478;108890.45554772175;118445.17201229894;121031;1037.7198156497702;1055.2929537210089;1068.5458680060942;1079.4818487126067;1088.0370871778532;17201.913990429966;19316.903415482833;19525.80474195297;19623.11695973656;19753.40739777809;48353;50122.4963251103;50855.98288034293;51237.231441529155;51798;65198.022029670545;73337.6333118331;73418;78248.60875695584;86758;7780.416468764752;7880.3301719354495;8072.1965439099795;8077.071136819341;8079.959600202196;11633.825492617787;12585.685357866127;12932.288724120264;13198.340082995619;13261.80107298372;10887.346759194474;11067.778644426906;11187.105031597615;11377.715557111138;11404.372759498307;9224.953875230582;10931.672049838518;11111.11111111093;11130.554777808797;11256.943715281372;9750.317477776487;9872.308938374244;11446.427678616203;11473.885261147281;11647.417629118341;7932.920670793449;8010.078840933367;8054;8077;8293.668253270043;8197.016358037074;10828.566857325934;11219;11245.269057511236;11421.543138274375 19:42:33 INFO - PROCESS | 1664 | |3;mootools.html;1365.8429280632724;1388.396047719243;1580.9683806323874;1761.242665653769;1980.1584326661175;7780.92219077809;8590.699325523608;8818.823623527525;8820.426672266298;8979.281657467403;8534.317254619631;8793.956030219855;8794.86807697885;8811.647534098644;8818.338624603148;1710.529604358799;2211.9493240710694;2291.0721157931066;2342.964855527164;2385.427497400629;2215.302179813362;2404.903803847844;2413.831031827764;2417.093589903786;2422.5033868057008;2332.125452955142;2358.8348815582917;2375;2380.7619238076227;2381;2058.4545095549693;2142.143142742915;2145.5387091775187;2145.7961493658077;2327.1273272522803;1903.5907279934881;2151.193302511558;2198.472366631997;2232.698585690943;2235.240018393754;8645.956770216171;8695.39132260742;8714.564271786448;8715.738527844174;8718.128187181295;5093;5253.947460525423;5255.1854462557985;5275.235090911835;5276.997370499593;3090.6136732908385;3541.3448512025357;3593.389123848952;3601.8919432416806;3693.279810436966;1038.989610103897;1076.6877605494396;1082.5290998415715;1095.4741723972538;1098.4288170151476;11578.015868651091;11600;11604.88395116055;11605.76788464226;11611.013063889579;4619.330197121399;4678.368420263221;4762.452317983407;4765.9523404766205;4866.6350023747655;9036.774080647905;9087.772805679779;9149.817003660022;9159.816803664022;9174.128457796498;5213;5213.348331458567;5215.895682086337;5220.973895130576;5222.608304377187;4377.409049778239;4515.345274935182;4760.904781904343;4906.31311616374;4931;3710.257948410275;3762.5108735864164;3819.9427008594894;3866.9613303867163;3873.3802591585213;1082.24783775277;1143.9256448330832;1157.1390905065814;1159.8898104680043;1159.9826002609968;911.0752586125088;915.5879854065697;936.6628013914985;941.4163218804385;987.456898705709;5384.300040994645;5683.516901063456;5705.115707065412;5940.405959404025;6025.608335458269;936.4896131608153;958.2956526952685;984.1437948984429;990.4997976022063;1008.9949550252202;3870;4003.899902502461;4018.1361007383553;4053.331200351909;4068.9186216275507;10325.277230593936;10396.431864052058;10658.946705266579;10723.94638026805;10748.140148788078;10666.253362264722;10730.087942524817;10830.187735919837;10838.458077096113;10875.075618572351;8138.226868447488;8194.549299788569;8238.382121340923;8313.750587482386;8405.453645513144;5733.197352370588;5865.14955331466;5894.793682221101;5938.4358485945495;5973.880522389441;8318.45930014547;8388.538630375388;9804.26468014916;10078.042585954323;10102.848457273294;8513.702020429502;9599.47202903847;10005.699829005142;10012.148967337567;10217.33587316822;6928.16861976566;7171.928280717126;7453.068366454193;7609.124950630749;7927.920720792718;8190.467619604707;8278.296344810758;8381.37139714536;8389.832203356022;8403.991521017273;7330.376917962033;7445.069366329209;7559.357454616419;7586.81032974158;7626.54240745557;7157.284271572801;7337.853242935218;7371.59456229891;7501.699932002659;7522.511036782592;6908.93091069103;6912.27421120775;6998.930010699828;7006.159260888726;7014.824629384306;4142.792860357031;4300.720453170534;4761.357216775691;4891.657583969088;4980.228064649985;4998.475160108136;5014.52362025607;5017.523335283142;5024.171011783015;5159.17453207485 19:42:33 INFO - PROCESS | 1664 | |4;prototype.html;274.9367645441548;303.63866998272056;310.849238119512;316.42568737741;319.9728023118035;4310.935335969955;4525.479569849468;5153.561947234485;5169.974150129249;5196.479707341946;5102.693838369701;5127.564157046646;5145.485451454852;5178.896422071556;5247.816326428576;3733.5893051764283;3739.7569158004644;3749.775013499185;3907.492026036611;4028.5971402859773;3864.574896761354;3937.2519221347998;3949.526056873179;3958.44581758554;3960.6237407446247;3339.899803005914;3431.7769344992494;3475.478678198278;3822.636080249377;3846.7307288489815;3732.346839303125;3883.5339759229073;3913.5695073541888;3944.881653550398;3962.9405558916637;3432.2449061206494;3434.8110853903027;3446.6897979181995;3472.982635086834;3478.3043391321585;4927.802887884516;4938;4953.504649535018;4985.451600323961;4989.176785830334;3980.7014473914282;4269.231538323101;4511.345854851061;4543.432070991126;4587;4535.727856328631;4643.582077612998;5103.438621751604;5261.4475480074425;5368.4363141869935;3849.711271654609;3861.3242682530727;3862.4978752761963;3863.787491687956;3864.2271545690974;5360.2227676987395;5489.752961116759;5589.379080066776;5706.857328566777;5766.279215098113;3879.359905615598;3888.580668056139;3891.2606604745006;3893.7274390792372;3899.493065901415;3423.726101911841;3762.6801721853403;3931.6068393160454;3965.5439624443557;4017.819198136091;4209.031922657745;4294.570542945743;4563.197536972877;4590.88522786933;4648.837290694877;4823.9517604824205;4997.600191984632;5123.744682552724;5446.972765136228;5463.672179669233;1181.291225264834;1348.2045593100117;1355.8372995240634;1358.4362489566918;1370.51299340215;743.3458556470272;820.7209548753452;854.3122786157203;858.2704701004096;862.8619420892627;4298.570142985676;4842.273658951185;4866.90722989111;4949.549972763505;4978.377702787152;5298.953101966385;5504.587155963239;5519.206257966097;5536.640118392372;5544.972275138679;4261.294078260265;4441.51275285841;4956.975215123974;4969.031038947371;4992.425871024806;2361.9527609447714;2618.4108575570344;2629.684437867468;2743.6570428696414;2907.4039821836473;5363.30277063987;5776.740046697909;5837.824865254049;5853.385394533598;5898.911516327258;5054.09026375256;5295.543725475448;5409.621326507186;5455.372632147354;5619.662820230799;4760.428748550127;5626.24314086103;5755.424457554211;5812.883742325214;5834.854128646818;5533.253010843485;5546.4453554644215;5553.72231388437;5577.689243027804;5580.414056524007;5134.255532947776;5256.8697729988235;5481.808136715196;5528.170774383874;5554.416786237549;4703.553162449562;5084.220522816984;5718.456016875503;5719.685417302088;5852.561057920588;2911.5632655101904;3226.918982141009;3269.9673003269663;3273.3960359424186;3328.6005679318637;4053.169100334483;4465.182269159822;4542.386777784956;4599.356090147315;4886.065074274937;4552.658550608651;4567.954320456885;4573.35972963792;4579.564941330568;4599.793009314522;4079.979600102099;4249.702520823636;4271.472239426218;4425.358323043205;4563.262042774309;3872.903177420587;4170.874873753793;4270.658347332143;4524.298733696282;4550.590446859798;4507.459104907301;4526.592606665416;4573;5206.635535512478;5242;4949.752512374439;4966.031623833318;5243.528082472596;5244.452886398598;5301.977872612574 19:42:33 INFO - PROCESS | 1664 | |5;yui.html;712.789727030526;767.3477544087526;831.3556993330172;840.9789755256118;848.2662496940148;15145.394184232631;15285.082895026306;15835.762463563042;16091.758623620655;16112.11383373914;14303.427862885472;14364.569062928129;14480.13119212848;14500.98493105483;14504.782428263585;708.4757279613075;726.4950859152891;884.2218847414282;895.8163576466841;897.228383590112;873.2795443758893;908.1871724806294;912.8904531456201;913.392593925039;914.4147745442922;957.0716405087089;963.210167662517;963.5423173992333;963.59047404853;965.1313817564178;751.8571471420413;921.6313474610142;952.9857052144223;954.8233576788316;955.7037318431309;626.2172284644195;672.9562578432386;703.8768215562308;704.651197657163;706.4383814867193;13152.6711832204;14366.20985845778;14855.405783768745;14922.850771492254;14956.850431495763;13552.59342219735;13670.166120857482;13711.177329360271;13754.105983111065;14010.509632162826;12293.815592766116;12371.195872268274;12389.814152787716;12516.937415312956;12574.371281435891;4535.886602834956;4541.227991241464;4557.453105627313;4646.326282689025;4672.929906051412;100708;103880.48059759653;108865.45567272192;109120;109481.45259273732;8533.914660853312;8535;8586.957065214634;8603.526806025604;8821.867671984925;14646.780298295535;14675.412983480755;14695.11829290246;20250.78495290287;21321;8318.918540589684;8327.167283271625;8393.160683931557;8413.326933845277;8536.615852286663;4504;4524.34397012438;4540.318952157203;4579.519150489151;4692.131955588227;3669.2478042001326;3776.735628505978;3782.0922978485514;3791.3175628387157;3803.5625903020955;496.71687138330805;669.1668872253978;678.5962352400314;680.425040840489;681.1961884975776;534.6150771444553;663.426136392027;678.7115475922714;708.6279703155884;744.7430636430423;8518.914810851813;9492.240620750323;9819.312648114706;9858.457784821903;9919.40483570988;624.8219257511587;632.9810105696837;662.4825662482547;699.2902204262678;710.041444050538;4587.9541204588195;4758.262469317261;5015.9725391377015;5188.403333616607;5305.363356397257;98478.52282215773;115790.68418631749;140567;140997.29501352634;161797;123943.38028309801;124903.375483122;131443.4610719261;132755.33622331827;135838.32080839534;108776;113711;114500;114691.42654286676;116834.41582792031;9963.651272205438;9994.500274986367;10177.185825133824;10190.796184076426;10209.132223760766;62856;63045;63200;63567;64177;83885;94840;97558.04883902424;98066;99092;13309.134906231064;13529.86470135286;13747.450101996208;13931.651708707363;14247.786283205967;14662.120272783666;14940.701185976437;15063.246837657854;15370.154641494379;15563.144027078619;13971.790423143864;14205.360758765673;14414.783778243125;14549.345279462237;14610.926945365205;13717.382717777924;13791.51729689456;14010.299485025911;14436.783448248074;14719.852801471849;13724.45102195901;13779.035467517178;13780.379882905494;13801.723965520834;13848.930755346159;11397.943010284895;11570.19008669385;11628.825567616661;11918.40407979615;11988.820167697317;10866.836997445203;11262.211645184758;11404.94297528507;11646.650600481998;11673.824892626788 19:42:33 INFO - PROCESS | 1664 | __end_tp_report 19:42:33 INFO - PROCESS | 1664 | __start_cc_report 19:42:33 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1248 19:42:33 INFO - PROCESS | 1664 | __end_cc_report 19:42:33 INFO - PROCESS | 1664 | __startTimestamp1465353753863__endTimestamp 19:42:33 INFO - PROCESS | 1664 | 19:42:33 INFO - PROCESS | 1664 | ------- Summary: start ------- 19:42:33 INFO - PROCESS | 1664 | Number of tests: 6 19:42:33 INFO - PROCESS | 1664 | 19:42:33 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5634.86 Median:5841.59 stddev:624.54 (10.7%) stddev-sans-first:599.43 19:42:33 INFO - PROCESS | 1664 | Values: 3318.5 3416.2 3418.6 3441.5 3495.8 5458.4 5525.5 5548.3 5599.9 5627.2 5571.1 5602.0 5605.5 5619.7 5631.7 5057.6 5091.8 5106.9 5118.4 5172.6 5143.4 5180.9 5192.9 5197.6 5204.2 5057.2 5093.5 5099.7 5103.8 5178.0 4581.0 5141.1 5187.7 5202.4 5204.0 4977.2 4979.3 5026.0 5122.1 5122.1 5437.2 5460.7 5499.2 5510.3 5525.8 5797.4 6101.8 6132.9 6133.6 6140.8 5841.6 5966.4 6113.2 6193.5 6247.4 5707.3 5750.1 5767.6 5769.0 5773.6 6257.2 6273.9 6279.0 6283.2 6286.7 5813.5 5878.6 5932.2 6032.8 6099.8 5176.5 6115.4 6152.8 6153.1 6155.6 6082.8 6098.6 6115.8 6116.6 6120.4 6040.5 6063.4 6102.1 6115.9 6141.3 4811.5 4840.9 4863.2 4873.2 4877.7 4344.3 4361.4 4370.9 4387.8 4419.0 5586.5 5598.8 5612.2 5617.2 5672.8 5947.5 6001.5 6055.5 6189.5 6202.3 5533.4 5540.9 5551.2 5552.8 5553.1 5237.1 5274.8 5275.5 5276.9 5280.9 6161.5 6253.8 6287.0 6292.4 6293.1 6214.5 6215.3 6216.0 6218.4 6238.4 6282.7 6311.9 6317.0 6320.4 6324.0 5945.8 5999.3 6024.2 6056.5 6094.9 6016.0 6153.9 6158.3 6174.4 6220.4 5991.3 6006.8 6007.4 6018.5 6023.1 5782.3 5855.9 5907.8 5930.9 5958.2 5865.4 5880.3 5887.9 5901.1 5910.9 6002.5 6018.8 6019.2 6044.3 6074.2 4958.9 5497.8 5784.7 5860.1 5885.4 19:42:33 INFO - PROCESS | 1664 | 19:42:33 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:68226.79 Median:39377.51 stddev:144857.28 (367.9%) stddev-sans-first:145174.41 19:42:33 INFO - PROCESS | 1664 | Values: 506.2 511.6 520.9 524.5 556.2 30693.7 32136.4 42025.2 43495.0 43526.6 44498.8 44869.9 44955.8 44988.6 45024.5 9431.9 9685.4 9695.7 9698.0 9706.0 8534.3 8774.0 11443.5 11741.2 11770.5 11738.5 11836.1 11859.1 11859.6 11873.3 8532.4 9842.4 11808.8 11819.6 11822.2 8573.9 8683.0 8695.6 11334.0 11722.0 41684.2 42168.2 42212.9 42221.8 42301.6 3605.1 3642.8 3719.5 3752.2 3844.4 32591.5 32613.0 32888.8 33319.5 33323.7 3297.0 3319.8 3326.5 3356.0 3417.9 397775.0 398743.0 399862.0 399986.0 400752.0 59564.4 60138.7 60411.0 60479.7 60485.0 64633.4 64854.7 64868.0 64913.7 65014.0 16236.2 16801.7 16830.1 16860.6 16916.8 11756.4 11990.5 12821.4 12991.9 13072.3 16098.4 16366.3 16376.0 16407.8 16564.7 7676.7 7678.5 7701.8 7708.5 7730.8 927.7 966.6 970.7 1085.0 1146.7 30510.7 30593.7 30703.8 31177.5 31562.8 1049.3 1069.3 1075.7 1106.4 1115.1 13618.9 15668.1 15849.4 15914.4 16116.6 568623.0 649679.0 667506.0 668444.0 670294.0 60454.4 60667.0 60692.0 60722.8 60727.7 1317.7 1425.2 1443.6 1467.7 1474.9 31915.5 32044.8 32072.0 32484.8 32609.7 50073.7 50400.5 50981.5 51073.7 51129.0 551287.0 553663.0 554971.0 555040.0 556415.2 34707.0 35401.0 41282.0 42354.7 42365.8 48906.8 49762.3 50245.2 50492.5 50528.5 31522.0 38668.8 38878.6 40923.8 41463.6 38527.8 39184.6 40032.4 40233.6 41093.0 38450.8 39343.2 39411.8 39734.0 41422.4 32805.0 34686.5 35397.5 35716.1 36177.1 22492.3 25681.7 25902.7 26205.5 26504.6 19:42:33 INFO - PROCESS | 1664 | 19:42:33 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:34394.28 Median:22116.34 stddev:99723.26 (450.9%) stddev-sans-first:99970.31 19:42:33 INFO - PROCESS | 1664 | Values: 383.3 384.7 390.4 391.1 414.4 17276.1 20089.4 20802.7 21129.7 21245.9 18701.6 19138.1 20709.8 21248.0 21305.3 4333.7 5745.3 5996.3 6004.9 6007.5 5848.2 5910.2 6035.8 6040.9 6043.3 5783.2 5904.9 5929.7 5967.0 5980.8 5785.4 5885.9 5889.6 5940.3 5943.0 5011.5 5223.5 5225.0 5225.1 5238.6 25687.7 25891.6 26184.1 26278.5 26485.7 2173.6 2176.8 2308.0 2373.0 2529.1 14738.7 15400.3 15577.9 16020.7 16061.1 1854.9 1917.5 1930.7 1935.9 1964.3 87643.6 117663.0 118602.0 122687.0 125650.4 11159.5 11287.6 11319.3 11352.8 11452.9 12392.4 14104.2 14110.9 14128.5 14228.2 7538.3 7926.8 8212.7 8748.3 8753.6 7073.6 7353.5 8809.0 8821.5 8958.6 5652.6 5719.4 6681.6 6843.7 6872.5 1721.9 1768.2 1816.6 1862.9 1875.0 979.9 1021.8 1041.7 1043.4 1043.9 20892.9 21305.9 21818.7 22414.0 22429.9 954.1 1001.6 1033.4 1041.4 1138.4 11191.0 11665.9 11807.2 11855.9 11878.0 592653.0 600399.0 601085.0 601580.0 611672.0 72258.3 97976.5 108890.5 118445.2 121031.0 1037.7 1055.3 1068.5 1079.5 1088.0 17201.9 19316.9 19525.8 19623.1 19753.4 48353.0 50122.5 50856.0 51237.2 51798.0 65198.0 73337.6 73418.0 78248.6 86758.0 7780.4 7880.3 8072.2 8077.1 8080.0 11633.8 12585.7 12932.3 13198.3 13261.8 10887.3 11067.8 11187.1 11377.7 11404.4 9225.0 10931.7 11111.1 11130.6 11256.9 9750.3 9872.3 11446.4 11473.9 11647.4 7932.9 8010.1 8054.0 8077.0 8293.7 8197.0 10828.6 11219.0 11245.3 11421.5 19:42:33 INFO - PROCESS | 1664 | 19:42:33 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5624.07 Median:4989.35 stddev:3155.07 (63.2%) stddev-sans-first:3147.69 19:42:33 INFO - PROCESS | 1664 | Values: 1365.8 1388.4 1581.0 1761.2 1980.2 7780.9 8590.7 8818.8 8820.4 8979.3 8534.3 8794.0 8794.9 8811.6 8818.3 1710.5 2211.9 2291.1 2343.0 2385.4 2215.3 2404.9 2413.8 2417.1 2422.5 2332.1 2358.8 2375.0 2380.8 2381.0 2058.5 2142.1 2145.5 2145.8 2327.1 1903.6 2151.2 2198.5 2232.7 2235.2 8646.0 8695.4 8714.6 8715.7 8718.1 5093.0 5253.9 5255.2 5275.2 5277.0 3090.6 3541.3 3593.4 3601.9 3693.3 1039.0 1076.7 1082.5 1095.5 1098.4 11578.0 11600.0 11604.9 11605.8 11611.0 4619.3 4678.4 4762.5 4766.0 4866.6 9036.8 9087.8 9149.8 9159.8 9174.1 5213.0 5213.3 5215.9 5221.0 5222.6 4377.4 4515.3 4760.9 4906.3 4931.0 3710.3 3762.5 3819.9 3867.0 3873.4 1082.2 1143.9 1157.1 1159.9 1160.0 911.1 915.6 936.7 941.4 987.5 5384.3 5683.5 5705.1 5940.4 6025.6 936.5 958.3 984.1 990.5 1009.0 3870.0 4003.9 4018.1 4053.3 4068.9 10325.3 10396.4 10658.9 10723.9 10748.1 10666.3 10730.1 10830.2 10838.5 10875.1 8138.2 8194.5 8238.4 8313.8 8405.5 5733.2 5865.1 5894.8 5938.4 5973.9 8318.5 8388.5 9804.3 10078.0 10102.8 8513.7 9599.5 10005.7 10012.1 10217.3 6928.2 7171.9 7453.1 7609.1 7927.9 8190.5 8278.3 8381.4 8389.8 8404.0 7330.4 7445.1 7559.4 7586.8 7626.5 7157.3 7337.9 7371.6 7501.7 7522.5 6908.9 6912.3 6998.9 7006.2 7014.8 4142.8 4300.7 4761.4 4891.7 4980.2 4998.5 5014.5 5017.5 5024.2 5159.2 19:42:33 INFO - PROCESS | 1664 | 19:42:33 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:4272.16 Median:4573.18 stddev:1302.94 (28.5%) stddev-sans-first:1271.58 19:42:33 INFO - PROCESS | 1664 | Values: 274.9 303.6 310.8 316.4 320.0 4310.9 4525.5 5153.6 5170.0 5196.5 5102.7 5127.6 5145.5 5178.9 5247.8 3733.6 3739.8 3749.8 3907.5 4028.6 3864.6 3937.3 3949.5 3958.4 3960.6 3339.9 3431.8 3475.5 3822.6 3846.7 3732.3 3883.5 3913.6 3944.9 3962.9 3432.2 3434.8 3446.7 3473.0 3478.3 4927.8 4938.0 4953.5 4985.5 4989.2 3980.7 4269.2 4511.3 4543.4 4587.0 4535.7 4643.6 5103.4 5261.4 5368.4 3849.7 3861.3 3862.5 3863.8 3864.2 5360.2 5489.8 5589.4 5706.9 5766.3 3879.4 3888.6 3891.3 3893.7 3899.5 3423.7 3762.7 3931.6 3965.5 4017.8 4209.0 4294.6 4563.2 4590.9 4648.8 4824.0 4997.6 5123.7 5447.0 5463.7 1181.3 1348.2 1355.8 1358.4 1370.5 743.3 820.7 854.3 858.3 862.9 4298.6 4842.3 4866.9 4949.5 4978.4 5299.0 5504.6 5519.2 5536.6 5545.0 4261.3 4441.5 4957.0 4969.0 4992.4 2362.0 2618.4 2629.7 2743.7 2907.4 5363.3 5776.7 5837.8 5853.4 5898.9 5054.1 5295.5 5409.6 5455.4 5619.7 4760.4 5626.2 5755.4 5812.9 5834.9 5533.3 5546.4 5553.7 5577.7 5580.4 5134.3 5256.9 5481.8 5528.2 5554.4 4703.6 5084.2 5718.5 5719.7 5852.6 2911.6 3226.9 3270.0 3273.4 3328.6 4053.2 4465.2 4542.4 4599.4 4886.1 4552.7 4568.0 4573.4 4579.6 4599.8 4080.0 4249.7 4271.5 4425.4 4563.3 3872.9 4170.9 4270.7 4524.3 4550.6 4507.5 4526.6 4573.0 5206.6 5242.0 4949.8 4966.0 5243.5 5244.5 5302.0 19:42:33 INFO - PROCESS | 1664 | 19:42:33 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:24531.77 Median:3797.44 stddev:38578.42 (1015.9%) stddev-sans-first:38645.18 19:42:33 INFO - PROCESS | 1664 | Values: 712.8 767.3 831.4 841.0 848.3 15145.4 15285.1 15835.8 16091.8 16112.1 14303.4 14364.6 14480.1 14501.0 14504.8 708.5 726.5 884.2 895.8 897.2 873.3 908.2 912.9 913.4 914.4 957.1 963.2 963.5 963.6 965.1 751.9 921.6 953.0 954.8 955.7 626.2 673.0 703.9 704.7 706.4 13152.7 14366.2 14855.4 14922.9 14956.9 13552.6 13670.2 13711.2 13754.1 14010.5 12293.8 12371.2 12389.8 12516.9 12574.4 4535.9 4541.2 4557.5 4646.3 4672.9 100708.0 103880.5 108865.5 109120.0 109481.5 8533.9 8535.0 8587.0 8603.5 8821.9 14646.8 14675.4 14695.1 20250.8 21321.0 8318.9 8327.2 8393.2 8413.3 8536.6 4504.0 4524.3 4540.3 4579.5 4692.1 3669.2 3776.7 3782.1 3791.3 3803.6 496.7 669.2 678.6 680.4 681.2 534.6 663.4 678.7 708.6 744.7 8518.9 9492.2 9819.3 9858.5 9919.4 624.8 633.0 662.5 699.3 710.0 4588.0 4758.3 5016.0 5188.4 5305.4 98478.5 115790.7 140567.0 140997.3 161797.0 123943.4 124903.4 131443.5 132755.3 135838.3 108776.0 113711.0 114500.0 114691.4 116834.4 9963.7 9994.5 10177.2 10190.8 10209.1 62856.0 63045.0 63200.0 63567.0 64177.0 83885.0 94840.0 97558.0 98066.0 99092.0 13309.1 13529.9 13747.5 13931.7 14247.8 14662.1 14940.7 15063.2 15370.2 15563.1 13971.8 14205.4 14414.8 14549.3 14610.9 13717.4 13791.5 14010.3 14436.8 14719.9 13724.5 13779.0 13780.4 13801.7 13848.9 11397.9 11570.2 11628.8 11918.4 11988.8 10866.8 11262.2 11404.9 11646.7 11673.8 19:42:33 INFO - PROCESS | 1664 | -------- Summary: end -------- 19:42:33 INFO - PROCESS | 1664 | 19:42:34 INFO - TEST-INFO | 1664: exit 0 19:42:34 INFO - TEST-OK | dromaeo_css | took 1117032ms 19:42:34 INFO - TEST-START | kraken 19:42:34 INFO - operating with platform_type : mac_ 19:42:34 INFO - Initialising browser for kraken test... 19:42:34 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/tmpAN9JCN/profile http://localhost:49278/getInfo.html) 19:42:36 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 19:42:36 INFO - PROCESS | 1671 | colorDepth:24 19:42:36 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 19:42:36 INFO - PROCESS | 1671 | __metrics 19:42:36 INFO - PROCESS | 1671 | 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:42:36 ERROR - PROCESS | 1671 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:42:37 INFO - TEST-INFO | 1671: exit 0 19:42:37 INFO - Browser initialized. 19:42:37 INFO - Running cycle 1/1 for kraken test... 19:42:37 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/tmpAN9JCN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:42:49 INFO - PROCESS | 1672 | RSS: Main: 242270208 19:42:49 INFO - PROCESS | 1672 | 19:42:52 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 19:42:53 INFO - PROCESS | 1672 | RSS: Main: 292229120 19:42:53 INFO - PROCESS | 1672 | 19:42:55 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 19:42:56 INFO - PROCESS | 1672 | RSS: Main: 301764608 19:42:56 INFO - PROCESS | 1672 | 19:42:59 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 19:43:00 INFO - PROCESS | 1672 | RSS: Main: 385544192 19:43:00 INFO - PROCESS | 1672 | 19:43:02 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 19:43:03 INFO - PROCESS | 1672 | RSS: Main: 288702464 19:43:03 INFO - PROCESS | 1672 | 19:43:05 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:43:05 INFO - PROCESS | 1672 | RSS: Main: 292442112 19:43:05 INFO - PROCESS | 1672 | 19:43:08 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 19:43:09 INFO - PROCESS | 1672 | RSS: Main: 330199040 19:43:09 INFO - PROCESS | 1672 | 19:43:12 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 19:43:12 INFO - PROCESS | 1672 | RSS: Main: 332783616 19:43:12 INFO - PROCESS | 1672 | 19:43:15 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 19:43:16 INFO - PROCESS | 1672 | RSS: Main: 329949184 19:43:16 INFO - PROCESS | 1672 | 19:43:17 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:43:18 INFO - PROCESS | 1672 | RSS: Main: 310063104 19:43:18 INFO - PROCESS | 1672 | 19:43:20 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:43:20 INFO - PROCESS | 1672 | RSS: Main: 316301312 19:43:20 INFO - PROCESS | 1672 | 19:43:23 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:43:23 INFO - PROCESS | 1672 | RSS: Main: 321589248 19:43:23 INFO - PROCESS | 1672 | 19:43:26 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:43:27 INFO - PROCESS | 1672 | RSS: Main: 323829760 19:43:27 INFO - PROCESS | 1672 | 19:43:30 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:43:30 INFO - PROCESS | 1672 | RSS: Main: 324292608 19:43:30 INFO - PROCESS | 1672 | 19:43:32 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 19:43:33 INFO - PROCESS | 1672 | RSS: Main: 367222784 19:43:33 INFO - PROCESS | 1672 | 19:43:33 INFO - PROCESS | 1672 | __start_tp_report 19:43:33 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 19:43:33 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 19:43:33 INFO - PROCESS | 1672 | |i|pagename|runs| 19:43:33 INFO - PROCESS | 1672 | |0;ai-astar;113;106;114;105;112;113;111;112;112;111 19:43:33 INFO - PROCESS | 1672 | |1;audio-beat-detection;156;118;116;168;118;119;142;117;150;119 19:43:33 INFO - PROCESS | 1672 | |2;audio-dft;181;184;182;184;183;183;187;183;181;182 19:43:33 INFO - PROCESS | 1672 | |3;audio-fft;79;74;57;107;56;57;61;62;92;56 19:43:33 INFO - PROCESS | 1672 | |4;audio-oscillator;85;89;93;72;71;68;97;72;68;101 19:43:33 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;88;86;89;86;88;127;89;86;88;85 19:43:33 INFO - PROCESS | 1672 | |6;imaging-darkroom;93;108;150;93;94;93;93;94;94;92 19:43:33 INFO - PROCESS | 1672 | |7;imaging-desaturate;86;85;84;83;85;85;86;84;86;85 19:43:33 INFO - PROCESS | 1672 | |8;json-parse-financial;53;55;53;55;53;54;54;55;52;55 19:43:33 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;68;78;66;74;76;71;70;72;73;70 19:43:33 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;86;87;86;86;85;85;83;87;125;108 19:43:33 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;140;144;140;145;154;135;140;132;153;138 19:43:33 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;168;166;166;166;165;166;165;164;198;213 19:43:33 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;69;67;78;81;87;82;84;83;73;66 19:43:33 INFO - PROCESS | 1672 | __end_tp_report 19:43:33 INFO - PROCESS | 1672 | __start_cc_report 19:43:33 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,1956 19:43:33 INFO - PROCESS | 1672 | __end_cc_report 19:43:33 INFO - PROCESS | 1672 | __startTimestamp1465353813056__endTimestamp 19:43:33 INFO - PROCESS | 1672 | 19:43:33 INFO - PROCESS | 1672 | ------- Summary: start ------- 19:43:33 INFO - PROCESS | 1672 | Number of tests: 14 19:43:33 INFO - PROCESS | 1672 | 19:43:33 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:110.90 Median:112.00 stddev:3.00 (2.7%) stddev-sans-first:3.08 19:43:33 INFO - PROCESS | 1672 | Values: 113.0 106.0 114.0 105.0 112.0 113.0 111.0 112.0 112.0 111.0 19:43:33 INFO - PROCESS | 1672 | 19:43:33 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:132.30 Median:130.50 stddev:19.74 (15.1%) stddev-sans-first:18.98 19:43:33 INFO - PROCESS | 1672 | Values: 156.0 118.0 116.0 168.0 118.0 119.0 142.0 117.0 150.0 119.0 19:43:33 INFO - PROCESS | 1672 | 19:43:33 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:183.00 Median:183.00 stddev:1.76 (1.0%) stddev-sans-first:1.72 19:43:33 INFO - PROCESS | 1672 | Values: 181.0 184.0 182.0 184.0 183.0 183.0 187.0 183.0 181.0 182.0 19:43:33 INFO - PROCESS | 1672 | 19:43:33 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:70.10 Median:61.50 stddev:17.72 (28.8%) stddev-sans-first:18.50 19:43:33 INFO - PROCESS | 1672 | Values: 79.0 74.0 57.0 107.0 56.0 57.0 61.0 62.0 92.0 56.0 19:43:33 INFO - PROCESS | 1672 | 19:43:33 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:81.60 Median:78.50 stddev:12.81 (16.3%) stddev-sans-first:13.53 19:43:33 INFO - PROCESS | 1672 | Values: 85.0 89.0 93.0 72.0 71.0 68.0 97.0 72.0 68.0 101.0 19:43:33 INFO - PROCESS | 1672 | 19:43:33 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:91.20 Median:88.00 stddev:12.66 (14.4%) stddev-sans-first:13.37 19:43:33 INFO - PROCESS | 1672 | Values: 88.0 86.0 89.0 86.0 88.0 127.0 89.0 86.0 88.0 85.0 19:43:33 INFO - PROCESS | 1672 | 19:43:33 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:100.40 Median:93.00 stddev:18.04 (19.4%) stddev-sans-first:18.94 19:43:33 INFO - PROCESS | 1672 | Values: 93.0 108.0 150.0 93.0 94.0 93.0 93.0 94.0 94.0 92.0 19:43:33 INFO - PROCESS | 1672 | 19:43:33 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:84.90 Median:85.00 stddev:0.99 (1.2%) stddev-sans-first:0.97 19:43:33 INFO - PROCESS | 1672 | Values: 86.0 85.0 84.0 83.0 85.0 85.0 86.0 84.0 86.0 85.0 19:43:33 INFO - PROCESS | 1672 | 19:43:33 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.50 stddev:1.10 (2.0%) stddev-sans-first:1.12 19:43:33 INFO - PROCESS | 1672 | Values: 53.0 55.0 53.0 55.0 53.0 54.0 54.0 55.0 52.0 55.0 19:43:33 INFO - PROCESS | 1672 | 19:43:33 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:72.50 stddev:3.61 (5.0%) stddev-sans-first:3.56 19:43:33 INFO - PROCESS | 1672 | Values: 68.0 78.0 66.0 74.0 76.0 71.0 70.0 72.0 73.0 70.0 19:43:33 INFO - PROCESS | 1672 | 19:43:33 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:91.80 Median:86.00 stddev:13.67 (15.9%) stddev-sans-first:14.34 19:43:33 INFO - PROCESS | 1672 | Values: 86.0 87.0 86.0 86.0 85.0 85.0 83.0 87.0 125.0 108.0 19:43:33 INFO - PROCESS | 1672 | 19:43:33 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:142.10 Median:142.00 stddev:7.11 (5.0%) stddev-sans-first:7.50 19:43:33 INFO - PROCESS | 1672 | Values: 140.0 144.0 140.0 145.0 154.0 135.0 140.0 132.0 153.0 138.0 19:43:33 INFO - PROCESS | 1672 | 19:43:33 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.70 Median:166.00 stddev:17.16 (10.3%) stddev-sans-first:18.08 19:43:33 INFO - PROCESS | 1672 | Values: 168.0 166.0 166.0 166.0 165.0 166.0 165.0 164.0 198.0 213.0 19:43:33 INFO - PROCESS | 1672 | 19:43:33 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.00 Median:81.50 stddev:7.66 (9.4%) stddev-sans-first:7.56 19:43:33 INFO - PROCESS | 1672 | Values: 69.0 67.0 78.0 81.0 87.0 82.0 84.0 83.0 73.0 66.0 19:43:33 INFO - PROCESS | 1672 | -------- Summary: end -------- 19:43:33 INFO - PROCESS | 1672 | 19:43:33 INFO - TEST-INFO | 1672: exit 0 19:43:33 INFO - TEST-OK | kraken | took 59424ms 19:43:33 INFO - SUITE-END | took 1176s 19:43:34 INFO - Completed test suite (00:19:36) 19:43:34 INFO - javascript benchmark 19:43:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3318.485634726617, 3416.2142707177354, 3418.589769227693, 3441.5353927219817, 3495.807730574819, 5458.426865179159, 5525.475079867411, 5548.278723765915, 5599.944000559994, 5627.240322556454, 5571.13647384656, 5602.0, 5605.523530499912, 5619.7471113799875, 5631.746571404286, 5057.620678449112, 5091.84724458265, 5106.948930510703, 5118.436971933084, 5172.560332371753, 5143.382794064699, 5180.870478238036, 5192.948070519303, 5197.636165468419, 5204.219367094947, 5057.216131499624, 5093.490650934914, 5099.745012749367, 5103.8468845934685, 5178.042062218483, 4581.015081757439, 5141.100307446182, 5187.662801917901, 5202.401723801736, 5204.011237864794, 4977.1538838397555, 4979.302897594303, 5025.974870125626, 5122.078025955327, 5122.1036318644465, 5437.211604317391, 5460.726963651801, 5499.175123731432, 5510.338759348863, 5525.778968841241, 5797.449242321973, 6101.816945491642, 6132.908006379907, 6133.601315914496, 6140.815775526741, 5841.591088623798, 5966.373530779292, 6113.23584551931, 6193.535484838655, 6247.406496382836, 5707.343655479632, 5750.0512415450985, 5767.625104368202, 5769.0, 5773.566982476331, 6257.2491301044165, 6273.905891411632, 6278.968605156945, 6283.214598175228, 6286.748530058747, 5813.476787089182, 5878.588498805043, 5932.169496270526, 6032.758689652453, 6099.75600975956, 5176.482351764794, 6115.388461153938, 6152.753889844445, 6153.077038444269, 6155.630662160284, 6082.756689732361, 6098.603590766586, 6115.755369785159, 6116.633002019804, 6120.387961203933, 6040.546958978094, 6063.393660633901, 6102.065131056412, 6115.877682446326, 6141.324454310023, 4811.542903424169, 4840.927386089212, 4863.221884498464, 4873.19592267279, 4877.731724755102, 4344.304911214191, 4361.389405483234, 4370.890727731832, 4387.780610969438, 4419.027813880941, 5586.469285417878, 5598.776048958078, 5612.186232996192, 5617.185508101385, 5672.801451949162, 5947.464728174485, 6001.519878409804, 6055.455009049207, 6189.5048396129105, 6202.2867370253, 5533.446655334435, 5540.9168862467095, 5551.195076713854, 5552.805651802168, 5553.111502159635, 5237.083510385667, 5274.841754747364, 5275.472452754694, 5276.92084618731, 5280.9471905281225, 6161.507079433634, 6253.812385628438, 6286.968565157145, 6292.370762923762, 6293.118963345044, 6214.533909956681, 6215.316315205414, 6215.96892015537, 6218.409251121137, 6238.376162383907, 6282.654454004891, 6311.873762524816, 6316.968415157895, 6320.399562041599, 6323.968380158254, 5945.762169513344, 5999.280086389661, 6024.246969128859, 6056.515478761777, 6094.908576371269, 6016.007358785751, 6153.861431366066, 6158.344601843255, 6174.38256174379, 6220.440160385587, 5991.310999234969, 6006.849828754316, 6007.369226231358, 6018.548608854266, 6023.126646636126, 5782.335031471434, 5855.9121613176685, 5907.793227237026, 5930.851728706644, 5958.225430693982, 5865.354810970875, 5880.323762767336, 5887.852803679943, 5901.085331773582, 5910.881782364415, 6002.489788368038, 6018.759249630141, 6019.18740969981, 6044.274687037584, 6074.210352654127, 4958.851234462972, 5497.76300332422, 5784.7107644618445, 5860.062390017577, 5885.352611212848], "value": 5596.1207699037495, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [506.2178933547667, 511.56005834981914, 520.864575210445, 524.4886235919978, 556.1907424697066, 30693.693063069364, 32136.35727285453, 42025.15949681005, 43495.0, 43526.56473435264, 44498.77750611243, 44869.87825304369, 44955.77522112393, 44988.550114498845, 45024.54975450245, 9431.905680943188, 9685.418874867495, 9695.660651877186, 9697.981711920253, 9706.0, 8534.317254619647, 8774.0, 11443.485043173077, 11741.236819606742, 11770.47032883518, 11738.471768770425, 11836.05311575072, 11859.051275897951, 11859.584914527994, 11873.287602743863, 8532.402731808714, 9842.360246583949, 11808.822867656992, 11819.586314479038, 11822.172447928691, 8573.914260857437, 8683.0, 8695.56522173895, 11334.0, 11722.003629691486, 41684.166316673494, 42168.15663686709, 42212.94467638303, 42221.788891055345, 42301.57698423038, 3605.0987253186704, 3642.7814331140216, 3719.497867787841, 3752.1745216052695, 3844.384898416268, 32591.51112733311, 32613.021609351756, 32888.835555822305, 33319.50020749691, 33323.6667633323, 3297.0273769237892, 3319.751018673609, 3326.501024846268, 3355.966440335614, 3417.8974630761118, 397775.0, 398743.0, 399862.0, 399985.9999999971, 400751.98496029916, 59564.40435595675, 60138.699306503186, 60411.0, 60479.69760151259, 60485.0, 64633.35366646367, 64854.67572662106, 64868.0, 64913.67543162254, 65014.0, 16236.188190590423, 16801.66396672084, 16830.07434591085, 16860.57848553796, 16916.830831691525, 11756.353400563052, 11990.520379184909, 12821.423035963218, 12991.935040324737, 13072.28102454355, 16098.356065757238, 16366.345346186019, 16376.0, 16407.835921640868, 16564.6687066258, 7676.692932282758, 7678.462507624525, 7701.768946931601, 7708.537487750753, 7730.7680769577, 927.7309580221795, 966.5505539923969, 970.7039352997404, 1084.978300433987, 1146.6846617180174, 30510.694893050786, 30593.69406305953, 30703.8464807679, 31177.532337014964, 31562.842185788923, 1049.2917280835406, 1069.2835800014009, 1075.6880504653718, 1106.446776611694, 1115.1413411672966, 13618.863811361958, 15668.138252396227, 15849.35346866424, 15914.363425462852, 16116.597085072966, 568623.0, 649679.0, 667506.0, 668444.0, 670294.0, 60454.39545604488, 60667.0, 60692.0, 60722.78554428887, 60727.69636151879, 1317.743040107189, 1425.2090089999942, 1443.5597142871525, 1467.735807554629, 1474.9115053096848, 31915.521267181477, 32044.83977580097, 32072.0, 32484.83757581197, 32609.673903260664, 50073.74963125161, 50400.49599503958, 50981.490185097675, 51073.744631278096, 51129.0, 551287.0, 553663.0, 554971.0, 555040.0, 556415.2179239078, 34707.0, 35401.0, 41281.96795080147, 42354.72935811807, 42365.78817105895, 48906.75546622244, 49762.25356619581, 50245.246321304476, 50492.49507505025, 50528.49471505385, 31522.0, 38668.806655967666, 38878.611213887496, 40923.795381024094, 41463.58536414597, 38527.8441646751, 39184.60815391924, 40032.3995140079, 40233.59766402416, 41093.0, 38450.807745961094, 39343.2131357377, 39411.80294098626, 39734.00664983399, 41422.378664319454, 32805.0, 34686.479702803976, 35397.46903796497, 35716.107097322776, 36177.09557260984, 22492.325230243117, 25681.743182567934, 25902.740972590786, 26205.475890482467, 26504.602430963165], "value": 20673.387665944876, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.28517315207137, 384.6518900395142, 390.4416684141678, 391.09463041557416, 414.3660199894161, 17276.136193190352, 20089.397318080464, 20802.687959680617, 21129.683054754172, 21245.893770531166, 18701.625967480675, 19138.13878375473, 20709.79290207101, 21248.0, 21305.25431609894, 4333.696641235115, 5745.253117094772, 5996.310424301196, 6004.879902401971, 6007.4593286604195, 5848.239728835246, 5910.202122713422, 6035.758569657209, 6040.879182416349, 6043.274807023164, 5783.248177736909, 5904.911426328608, 5929.673867937262, 5967.0, 5980.79067232647, 5785.392533783935, 5885.941140588604, 5889.646621202742, 5940.257467816523, 5942.970285148546, 5011.548960593527, 5223.52988231061, 5225.033368826779, 5225.137852254374, 5238.554722848525, 25687.7156142194, 25891.611625825626, 26184.08355707541, 26278.474430511473, 26485.73514264852, 2173.576152650234, 2176.81497072749, 2308.0306271366067, 2372.9871983585276, 2529.1021687300927, 14738.70522589553, 15400.30698618565, 15577.90954633176, 16020.679586408323, 16061.116638584874, 1854.9072546372627, 1917.5206198450387, 1930.7296978423033, 1935.8741681790639, 1964.2634012245408, 87643.56178219132, 117663.0, 118602.0, 122687.0, 125650.3717481416, 11159.497822598003, 11287.604933827277, 11319.264247823865, 11352.829707554394, 11452.885471145182, 12392.442340094718, 14104.224267665171, 14110.929445352913, 14128.505502307575, 14228.217448040457, 7538.283863033003, 7926.801829954182, 8212.712555060543, 8748.256398206096, 8753.606087726068, 7073.575585464786, 7353.522021068614, 8809.03100658927, 8821.470711757314, 8958.641654333753, 5652.604317697722, 5719.399463056319, 6681.632510211888, 6843.657817109125, 6872.484563657746, 1721.8794684371974, 1768.2484943898792, 1816.6366726654458, 1862.9254829806655, 1874.9625007500047, 979.8677178580942, 1021.8109649714827, 1041.7133495665282, 1043.4209013997154, 1043.9008294212038, 20892.89553552253, 21305.893470532857, 21818.672719909213, 22414.0, 22429.887850560644, 954.117441366733, 1001.5543083327995, 1033.4367769565604, 1041.4324193314662, 1138.385271953136, 11191.0, 11665.88334116648, 11807.23252988553, 11855.881441185651, 11878.049756019498, 592653.0, 600399.0, 601085.0, 601580.0, 611672.0, 72258.2774172262, 97976.53035204478, 108890.45554772175, 118445.17201229894, 121031.0, 1037.7198156497702, 1055.2929537210089, 1068.5458680060942, 1079.4818487126067, 1088.0370871778532, 17201.913990429966, 19316.903415482833, 19525.80474195297, 19623.11695973656, 19753.40739777809, 48353.0, 50122.4963251103, 50855.98288034293, 51237.231441529155, 51798.0, 65198.022029670545, 73337.6333118331, 73418.0, 78248.60875695584, 86758.0, 7780.416468764752, 7880.3301719354495, 8072.1965439099795, 8077.071136819341, 8079.959600202196, 11633.825492617787, 12585.685357866127, 12932.288724120264, 13198.340082995619, 13261.80107298372, 10887.346759194474, 11067.778644426906, 11187.105031597615, 11377.715557111138, 11404.372759498307, 9224.953875230582, 10931.672049838518, 11111.11111111093, 11130.554777808797, 11256.943715281372, 9750.317477776487, 9872.308938374244, 11446.427678616203, 11473.885261147281, 11647.417629118341, 7932.920670793449, 8010.078840933367, 8054.0, 8077.0, 8293.668253270043, 8197.016358037074, 10828.566857325934, 11219.0, 11245.269057511236, 11421.543138274375], "value": 9718.59365376642, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1365.8429280632724, 1388.396047719243, 1580.9683806323874, 1761.242665653769, 1980.1584326661175, 7780.92219077809, 8590.699325523608, 8818.823623527525, 8820.426672266298, 8979.281657467403, 8534.317254619631, 8793.956030219855, 8794.86807697885, 8811.647534098644, 8818.338624603148, 1710.529604358799, 2211.9493240710694, 2291.0721157931066, 2342.964855527164, 2385.427497400629, 2215.302179813362, 2404.903803847844, 2413.831031827764, 2417.093589903786, 2422.5033868057008, 2332.125452955142, 2358.8348815582917, 2375.0, 2380.7619238076227, 2381.0, 2058.4545095549693, 2142.143142742915, 2145.5387091775187, 2145.7961493658077, 2327.1273272522803, 1903.5907279934881, 2151.193302511558, 2198.472366631997, 2232.698585690943, 2235.240018393754, 8645.956770216171, 8695.39132260742, 8714.564271786448, 8715.738527844174, 8718.128187181295, 5093.0, 5253.947460525423, 5255.1854462557985, 5275.235090911835, 5276.997370499593, 3090.6136732908385, 3541.3448512025357, 3593.389123848952, 3601.8919432416806, 3693.279810436966, 1038.989610103897, 1076.6877605494396, 1082.5290998415715, 1095.4741723972538, 1098.4288170151476, 11578.015868651091, 11600.0, 11604.88395116055, 11605.76788464226, 11611.013063889579, 4619.330197121399, 4678.368420263221, 4762.452317983407, 4765.9523404766205, 4866.6350023747655, 9036.774080647905, 9087.772805679779, 9149.817003660022, 9159.816803664022, 9174.128457796498, 5213.0, 5213.348331458567, 5215.895682086337, 5220.973895130576, 5222.608304377187, 4377.409049778239, 4515.345274935182, 4760.904781904343, 4906.31311616374, 4931.0, 3710.257948410275, 3762.5108735864164, 3819.9427008594894, 3866.9613303867163, 3873.3802591585213, 1082.24783775277, 1143.9256448330832, 1157.1390905065814, 1159.8898104680043, 1159.9826002609968, 911.0752586125088, 915.5879854065697, 936.6628013914985, 941.4163218804385, 987.456898705709, 5384.300040994645, 5683.516901063456, 5705.115707065412, 5940.405959404025, 6025.608335458269, 936.4896131608153, 958.2956526952685, 984.1437948984429, 990.4997976022063, 1008.9949550252202, 3870.0, 4003.899902502461, 4018.1361007383553, 4053.331200351909, 4068.9186216275507, 10325.277230593936, 10396.431864052058, 10658.946705266579, 10723.94638026805, 10748.140148788078, 10666.253362264722, 10730.087942524817, 10830.187735919837, 10838.458077096113, 10875.075618572351, 8138.226868447488, 8194.549299788569, 8238.382121340923, 8313.750587482386, 8405.453645513144, 5733.197352370588, 5865.14955331466, 5894.793682221101, 5938.4358485945495, 5973.880522389441, 8318.45930014547, 8388.538630375388, 9804.26468014916, 10078.042585954323, 10102.848457273294, 8513.702020429502, 9599.47202903847, 10005.699829005142, 10012.148967337567, 10217.33587316822, 6928.16861976566, 7171.928280717126, 7453.068366454193, 7609.124950630749, 7927.920720792718, 8190.467619604707, 8278.296344810758, 8381.37139714536, 8389.832203356022, 8403.991521017273, 7330.376917962033, 7445.069366329209, 7559.357454616419, 7586.81032974158, 7626.54240745557, 7157.284271572801, 7337.853242935218, 7371.59456229891, 7501.699932002659, 7522.511036782592, 6908.93091069103, 6912.27421120775, 6998.930010699828, 7006.159260888726, 7014.824629384306, 4142.792860357031, 4300.720453170534, 4761.357216775691, 4891.657583969088, 4980.228064649985, 4998.475160108136, 5014.52362025607, 5017.523335283142, 5024.171011783015, 5159.17453207485], "value": 4516.181166376933, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [274.9367645441548, 303.63866998272056, 310.849238119512, 316.42568737741, 319.9728023118035, 4310.935335969955, 4525.479569849468, 5153.561947234485, 5169.974150129249, 5196.479707341946, 5102.693838369701, 5127.564157046646, 5145.485451454852, 5178.896422071556, 5247.816326428576, 3733.5893051764283, 3739.7569158004644, 3749.775013499185, 3907.492026036611, 4028.5971402859773, 3864.574896761354, 3937.2519221347998, 3949.526056873179, 3958.44581758554, 3960.6237407446247, 3339.899803005914, 3431.7769344992494, 3475.478678198278, 3822.636080249377, 3846.7307288489815, 3732.346839303125, 3883.5339759229073, 3913.5695073541888, 3944.881653550398, 3962.9405558916637, 3432.2449061206494, 3434.8110853903027, 3446.6897979181995, 3472.982635086834, 3478.3043391321585, 4927.802887884516, 4938.0, 4953.504649535018, 4985.451600323961, 4989.176785830334, 3980.7014473914282, 4269.231538323101, 4511.345854851061, 4543.432070991126, 4587.0, 4535.727856328631, 4643.582077612998, 5103.438621751604, 5261.4475480074425, 5368.4363141869935, 3849.711271654609, 3861.3242682530727, 3862.4978752761963, 3863.787491687956, 3864.2271545690974, 5360.2227676987395, 5489.752961116759, 5589.379080066776, 5706.857328566777, 5766.279215098113, 3879.359905615598, 3888.580668056139, 3891.2606604745006, 3893.7274390792372, 3899.493065901415, 3423.726101911841, 3762.6801721853403, 3931.6068393160454, 3965.5439624443557, 4017.819198136091, 4209.031922657745, 4294.570542945743, 4563.197536972877, 4590.88522786933, 4648.837290694877, 4823.9517604824205, 4997.600191984632, 5123.744682552724, 5446.972765136228, 5463.672179669233, 1181.291225264834, 1348.2045593100117, 1355.8372995240634, 1358.4362489566918, 1370.51299340215, 743.3458556470272, 820.7209548753452, 854.3122786157203, 858.2704701004096, 862.8619420892627, 4298.570142985676, 4842.273658951185, 4866.90722989111, 4949.549972763505, 4978.377702787152, 5298.953101966385, 5504.587155963239, 5519.206257966097, 5536.640118392372, 5544.972275138679, 4261.294078260265, 4441.51275285841, 4956.975215123974, 4969.031038947371, 4992.425871024806, 2361.9527609447714, 2618.4108575570344, 2629.684437867468, 2743.6570428696414, 2907.4039821836473, 5363.30277063987, 5776.740046697909, 5837.824865254049, 5853.385394533598, 5898.911516327258, 5054.09026375256, 5295.543725475448, 5409.621326507186, 5455.372632147354, 5619.662820230799, 4760.428748550127, 5626.24314086103, 5755.424457554211, 5812.883742325214, 5834.854128646818, 5533.253010843485, 5546.4453554644215, 5553.72231388437, 5577.689243027804, 5580.414056524007, 5134.255532947776, 5256.8697729988235, 5481.808136715196, 5528.170774383874, 5554.416786237549, 4703.553162449562, 5084.220522816984, 5718.456016875503, 5719.685417302088, 5852.561057920588, 2911.5632655101904, 3226.918982141009, 3269.9673003269663, 3273.3960359424186, 3328.6005679318637, 4053.169100334483, 4465.182269159822, 4542.386777784956, 4599.356090147315, 4886.065074274937, 4552.658550608651, 4567.954320456885, 4573.35972963792, 4579.564941330568, 4599.793009314522, 4079.979600102099, 4249.702520823636, 4271.472239426218, 4425.358323043205, 4563.262042774309, 3872.903177420587, 4170.874873753793, 4270.658347332143, 4524.298733696282, 4550.590446859798, 4507.459104907301, 4526.592606665416, 4573.0, 5206.635535512478, 5242.0, 4949.752512374439, 4966.031623833318, 5243.528082472596, 5244.452886398598, 5301.977872612574], "value": 3863.127404133942, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.789727030526, 767.3477544087526, 831.3556993330172, 840.9789755256118, 848.2662496940148, 15145.394184232631, 15285.082895026306, 15835.762463563042, 16091.758623620655, 16112.11383373914, 14303.427862885472, 14364.569062928129, 14480.13119212848, 14500.98493105483, 14504.782428263585, 708.4757279613075, 726.4950859152891, 884.2218847414282, 895.8163576466841, 897.228383590112, 873.2795443758893, 908.1871724806294, 912.8904531456201, 913.392593925039, 914.4147745442922, 957.0716405087089, 963.210167662517, 963.5423173992333, 963.59047404853, 965.1313817564178, 751.8571471420413, 921.6313474610142, 952.9857052144223, 954.8233576788316, 955.7037318431309, 626.2172284644195, 672.9562578432386, 703.8768215562308, 704.651197657163, 706.4383814867193, 13152.6711832204, 14366.20985845778, 14855.405783768745, 14922.850771492254, 14956.850431495763, 13552.59342219735, 13670.166120857482, 13711.177329360271, 13754.105983111065, 14010.509632162826, 12293.815592766116, 12371.195872268274, 12389.814152787716, 12516.937415312956, 12574.371281435891, 4535.886602834956, 4541.227991241464, 4557.453105627313, 4646.326282689025, 4672.929906051412, 100708.0, 103880.48059759653, 108865.45567272192, 109120.0, 109481.45259273732, 8533.914660853312, 8535.0, 8586.957065214634, 8603.526806025604, 8821.867671984925, 14646.780298295535, 14675.412983480755, 14695.11829290246, 20250.78495290287, 21321.0, 8318.918540589684, 8327.167283271625, 8393.160683931557, 8413.326933845277, 8536.615852286663, 4504.0, 4524.34397012438, 4540.318952157203, 4579.519150489151, 4692.131955588227, 3669.2478042001326, 3776.735628505978, 3782.0922978485514, 3791.3175628387157, 3803.5625903020955, 496.71687138330805, 669.1668872253978, 678.5962352400314, 680.425040840489, 681.1961884975776, 534.6150771444553, 663.426136392027, 678.7115475922714, 708.6279703155884, 744.7430636430423, 8518.914810851813, 9492.240620750323, 9819.312648114706, 9858.457784821903, 9919.40483570988, 624.8219257511587, 632.9810105696837, 662.4825662482547, 699.2902204262678, 710.041444050538, 4587.9541204588195, 4758.262469317261, 5015.9725391377015, 5188.403333616607, 5305.363356397257, 98478.52282215773, 115790.68418631749, 140567.0, 140997.29501352634, 161797.0, 123943.38028309801, 124903.375483122, 131443.4610719261, 132755.33622331827, 135838.32080839534, 108776.0, 113711.0, 114500.0, 114691.42654286676, 116834.41582792031, 9963.651272205438, 9994.500274986367, 10177.185825133824, 10190.796184076426, 10209.132223760766, 62856.0, 63045.0, 63200.0, 63567.0, 64177.0, 83885.0, 94840.0, 97558.04883902424, 98066.0, 99092.0, 13309.134906231064, 13529.86470135286, 13747.450101996208, 13931.651708707363, 14247.786283205967, 14662.120272783666, 14940.701185976437, 15063.246837657854, 15370.154641494379, 15563.144027078619, 13971.790423143864, 14205.360758765673, 14414.783778243125, 14549.345279462237, 14610.926945365205, 13717.382717777924, 13791.51729689456, 14010.299485025911, 14436.783448248074, 14719.852801471849, 13724.45102195901, 13779.035467517178, 13780.379882905494, 13801.723965520834, 13848.930755346159, 11397.943010284895, 11570.19008669385, 11628.825567616661, 11918.40407979615, 11988.820167697317, 10866.836997445203, 11262.211645184758, 11404.94297528507, 11646.650600481998, 11673.824892626788], "value": 7947.030462619872, "unit": "score"}], "name": "dromaeo_css", "value": 7336.317605469512, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 106.0, 114.0, 105.0, 112.0, 113.0, 111.0, 112.0, 112.0, 111.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 118.0, 116.0, 168.0, 118.0, 119.0, 142.0, 117.0, 150.0, 119.0], "value": 132.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 184.0, 182.0, 184.0, 183.0, 183.0, 187.0, 183.0, 181.0, 182.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 57.0, 107.0, 56.0, 57.0, 61.0, 62.0, 92.0, 56.0], "value": 70.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 89.0, 93.0, 72.0, 71.0, 68.0, 97.0, 72.0, 68.0, 101.0], "value": 81.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 86.0, 88.0, 127.0, 89.0, 86.0, 88.0, 85.0], "value": 91.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 108.0, 150.0, 93.0, 94.0, 93.0, 93.0, 94.0, 94.0, 92.0], "value": 100.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 84.0, 83.0, 85.0, 85.0, 86.0, 84.0, 86.0, 85.0], "value": 84.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 53.0, 55.0, 53.0, 54.0, 54.0, 55.0, 52.0, 55.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 78.0, 66.0, 74.0, 76.0, 71.0, 70.0, 72.0, 73.0, 70.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 86.0, 86.0, 85.0, 85.0, 83.0, 87.0, 125.0, 108.0], "value": 91.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 144.0, 140.0, 145.0, 154.0, 135.0, 140.0, 132.0, 153.0, 138.0], "value": 142.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 166.0, 166.0, 166.0, 165.0, 166.0, 165.0, 164.0, 198.0, 213.0], "value": 173.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 78.0, 81.0, 87.0, 82.0, 84.0, 83.0, 73.0, 66.0], "value": 77.0, "unit": "score"}], "name": "kraken", "value": 1464.7, "alertThreshold": 2.0}]} 19:43:34 INFO - Return code: 0 19:43:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:43:34 ERROR - # TBPL SUCCESS # 19:43:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:43:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:43:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:43:34 INFO - 2016-06-07 19:43:34.357 screenresolution[1676:9973] starting screenresolution argv=screenresolution get 19:43:34 INFO - 2016-06-07 19:43:34.360 screenresolution[1676:9973] Display 0: 1600x1200x32@60 19:43:34 INFO - 2016-06-07 19:43:34.366 screenresolution[1677:9976] starting screenresolution argv=screenresolution list 19:43:34 INFO - Available Modes on Display 0 19:43:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:43:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:43:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:43:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:43:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:43:34 INFO - Intel Iris: 19:43:34 INFO - Chipset Model: Intel Iris 19:43:34 INFO - Type: GPU 19:43:34 INFO - Bus: Built-In 19:43:34 INFO - VRAM (Dynamic, Max): 1536 MB 19:43:34 INFO - Vendor: Intel (0x8086) 19:43:34 INFO - Device ID: 0x0a2e 19:43:34 INFO - Revision ID: 0x0009 19:43:34 INFO - Displays: 19:43:34 INFO - Display: 19:43:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:43:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:43:34 INFO - Display Serial Number: 1600x1200 60 19:43:34 INFO - Main Display: Yes 19:43:34 INFO - Mirror: Off 19:43:34 INFO - Online: Yes 19:43:34 INFO - Rotation: Supported 19:43:34 INFO - Return code: 0 19:43:34 INFO - Running post-action listener: _resource_record_post_action 19:43:34 INFO - ##### 19:43:34 INFO - ##### Finished run-tests step (success) 19:43:34 INFO - ##### 19:43:34 INFO - Running post-run listener: _resource_record_post_run 19:43:34 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 36795904; Write bytes: 696027136; Read time: 244; Write time: 1905 19:43:34 INFO - TinderboxPrint: CPU usage
53.7% 19:43:34 INFO - TinderboxPrint: I/O read bytes / time
36,795,904 / 244 19:43:34 INFO - TinderboxPrint: I/O write bytes / time
696,027,136 / 1,905 19:43:34 INFO - TinderboxPrint: CPU idle
2,245.7 (46.3%) 19:43:34 INFO - TinderboxPrint: CPU system
963.5 (19.9%) 19:43:34 INFO - TinderboxPrint: CPU user
1,640.5 (33.8%) 19:43:34 INFO - TinderboxPrint: Swap in / out
436,113,408 / 0 19:43:34 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 384121344; Write bytes: 373442048; Read time: 28083; Write time: 766 19:43:34 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 30629376; Write bytes: 293270016; Read time: 185; Write time: 1073 19:43:35 INFO - Running post-run listener: _upload_blobber_files 19:43:35 INFO - Blob upload gear active. 19:43:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:43:35 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:43:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:43:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:43:35 INFO - (blobuploader) - INFO - Open directory for files ... 19:43:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:43:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:43:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:43:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:43:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:43:36 INFO - (blobuploader) - INFO - Done attempting. 19:43:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:43:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:43:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:43:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:43:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:43:37 INFO - (blobuploader) - INFO - Done attempting. 19:43:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:43:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:43:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:43:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:43:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:43:40 INFO - (blobuploader) - INFO - Done attempting. 19:43:40 INFO - (blobuploader) - INFO - Iteration through files over. 19:43:40 INFO - Return code: 0 19:43:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:43:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:43:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d7ccf2499b68cd1226a73477fd6f7e62f849a66c1cfbb1030e41be9f876aee16d3631e714236b629a99270c166d6ad0191a126947aa34e59ded1112c39c05fcb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5a9436415698eba34ecf8e8267ca8659fcdd7425e097580884fecd0a0afc48dd361296b7d990811e3f6355c9cc804bc52c4bbd1a254a41925228c6bfa6e7ffe7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/eb9c42521be3fac2df52f7b15d5494b30e82b54627e02155ff66a02cdc271d0c15fd559394c6b2815564a2ea2fa836685d5e82b95c701057e42ec789b8d2b766"} 19:43:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:43:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:43:40 INFO - Contents: 19:43:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d7ccf2499b68cd1226a73477fd6f7e62f849a66c1cfbb1030e41be9f876aee16d3631e714236b629a99270c166d6ad0191a126947aa34e59ded1112c39c05fcb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5a9436415698eba34ecf8e8267ca8659fcdd7425e097580884fecd0a0afc48dd361296b7d990811e3f6355c9cc804bc52c4bbd1a254a41925228c6bfa6e7ffe7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/eb9c42521be3fac2df52f7b15d5494b30e82b54627e02155ff66a02cdc271d0c15fd559394c6b2815564a2ea2fa836685d5e82b95c701057e42ec789b8d2b766"} 19:43:40 INFO - Running post-run listener: copy_logs_to_upload_dir 19:43:40 INFO - Copying logs to upload dir... 19:43:40 INFO - mkdir: /builds/slave/test/build/upload/logs 19:43:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.774816 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-06-07 19:43:40.240011) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-07 19:43:40.243597) ========= 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.ch4bkMonA2/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.miUwxOeUCg/Listeners TMPDIR=/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d7ccf2499b68cd1226a73477fd6f7e62f849a66c1cfbb1030e41be9f876aee16d3631e714236b629a99270c166d6ad0191a126947aa34e59ded1112c39c05fcb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5a9436415698eba34ecf8e8267ca8659fcdd7425e097580884fecd0a0afc48dd361296b7d990811e3f6355c9cc804bc52c4bbd1a254a41925228c6bfa6e7ffe7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/eb9c42521be3fac2df52f7b15d5494b30e82b54627e02155ff66a02cdc271d0c15fd559394c6b2815564a2ea2fa836685d5e82b95c701057e42ec789b8d2b766"} build_url:https://queue.taskcluster.net/v1/task/Z_vZY1aJSVyoyiK2em2GUw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011332 build_url: 'https://queue.taskcluster.net/v1/task/Z_vZY1aJSVyoyiK2em2GUw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d7ccf2499b68cd1226a73477fd6f7e62f849a66c1cfbb1030e41be9f876aee16d3631e714236b629a99270c166d6ad0191a126947aa34e59ded1112c39c05fcb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5a9436415698eba34ecf8e8267ca8659fcdd7425e097580884fecd0a0afc48dd361296b7d990811e3f6355c9cc804bc52c4bbd1a254a41925228c6bfa6e7ffe7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/eb9c42521be3fac2df52f7b15d5494b30e82b54627e02155ff66a02cdc271d0c15fd559394c6b2815564a2ea2fa836685d5e82b95c701057e42ec789b8d2b766"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-07 19:43:40.276063) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 19:43:40.276390) ========= 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.ch4bkMonA2/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.miUwxOeUCg/Listeners TMPDIR=/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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.004662 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 19:43:40.325502) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-07 19:43:40.325907) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-07 19:43:40.326275) ========= ========= Total master_lag: 0.46 =========