builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0030 starttime: 1464079282.32 results: success (0) buildid: 20160523171639 builduid: 2df6f63a3d754fefa021eb2c65da7322 revision: 46fe2115d46a5bb40523b8466341d8f9a26e1bdf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:22.324932) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:22.325342) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:22.325615) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:22.359690) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:22.360163) ========= 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.BZNpbYMdUk/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.aSY25GHG9W/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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-05-24 01:41: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% 18.3M=0.001s 2016-05-24 01:41:22 (18.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.135774 ========= master_lag: 0.12 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:22.619346) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:22.619667) ========= 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.BZNpbYMdUk/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.aSY25GHG9W/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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.029235 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:22.665429) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 01:41:22.665771) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 46fe2115d46a5bb40523b8466341d8f9a26e1bdf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 46fe2115d46a5bb40523b8466341d8f9a26e1bdf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BZNpbYMdUk/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.aSY25GHG9W/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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-05-24 01:41:22,741 truncating revision to first 12 chars 2016-05-24 01:41:22,741 Setting DEBUG logging. 2016-05-24 01:41:22,741 attempt 1/10 2016-05-24 01:41:22,741 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/46fe2115d46a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-24 01:41:23,631 unpacking tar archive at: mozilla-central-46fe2115d46a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.151198 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 01:41:23.835397) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:23.835729) ========= script_repo_revision: 46fe2115d46a5bb40523b8466341d8f9a26e1bdf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:23.836118) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:23.836406) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:23.852079) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-05-24 01:41:23.852786) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BZNpbYMdUk/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.aSY25GHG9W/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 01:41:24 INFO - MultiFileLogger online at 20160524 01:41:24 in /builds/slave/test 01:41:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 01:41:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:41:24 INFO - {'append_to_log': False, 01:41:24 INFO - 'base_work_dir': '/builds/slave/test', 01:41:24 INFO - 'blob_upload_branch': 'Firefox', 01:41:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:41:24 INFO - 'branch': 'Firefox', 01:41:24 INFO - 'buildbot_json_path': 'buildprops.json', 01:41:24 INFO - 'config_files': ('talos/mac_config.py',), 01:41:24 INFO - 'default_actions': ('clobber', 01:41:24 INFO - 'read-buildbot-config', 01:41:24 INFO - 'download-and-extract', 01:41:24 INFO - 'populate-webroot', 01:41:24 INFO - 'create-virtualenv', 01:41:24 INFO - 'install', 01:41:24 INFO - 'run-tests'), 01:41:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:41:24 INFO - 'download_minidump_stackwalk': True, 01:41:24 INFO - 'download_symbols': 'ondemand', 01:41:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:41:24 INFO - 'tooltool.py': '/tools/tooltool.py', 01:41:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:41:24 INFO - '/tools/misc-python/virtualenv.py')}, 01:41:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:41:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:41:24 INFO - 'installer_path': 'installer.exe', 01:41:24 INFO - 'log_level': 'info', 01:41:24 INFO - 'log_name': 'talos', 01:41:24 INFO - 'log_to_console': True, 01:41:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:41:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:41:24 INFO - 'opt_config_files': (), 01:41:24 INFO - 'pip_index': False, 01:41:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:41:24 INFO - 'cmd': ('bash', 01:41:24 INFO - '-c', 01:41:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:41:24 INFO - 'enabled': True, 01:41:24 INFO - 'halt_on_failure': False, 01:41:24 INFO - 'name': 'check_screen_resolution'},), 01:41:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:41:24 INFO - 'cmd': ('bash', 01:41:24 INFO - '-c', 01:41:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:41:24 INFO - 'enabled': True, 01:41:24 INFO - 'halt_on_failure': False, 01:41:24 INFO - 'name': 'check_screen_resolution'},), 01:41:24 INFO - 'run_cmd_checks_enabled': True, 01:41:24 INFO - 'sps_profile': False, 01:41:24 INFO - 'sps_profile_interval': 0, 01:41:24 INFO - 'suite': 'dromaeojs', 01:41:24 INFO - 'system_bits': '32', 01:41:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:41:24 INFO - 'title': 't-yosemite-r7-0030', 01:41:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:41:24 INFO - 'use_talos_json': True, 01:41:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:41:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:41:24 INFO - 'work_dir': 'build'} 01:41:24 INFO - ##### 01:41:24 INFO - ##### Running clobber step. 01:41:24 INFO - ##### 01:41:24 INFO - Running pre-action listener: _resource_record_pre_action 01:41:24 INFO - Running main action method: clobber 01:41:24 INFO - rmtree: /builds/slave/test/build 01:41:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:41:26 INFO - Running post-action listener: _resource_record_post_action 01:41:26 INFO - ##### 01:41:26 INFO - ##### Finished clobber step (success) 01:41:26 INFO - ##### 01:41:26 INFO - ##### 01:41:26 INFO - ##### Running read-buildbot-config step. 01:41:26 INFO - ##### 01:41:26 INFO - Running pre-action listener: _resource_record_pre_action 01:41:26 INFO - Running main action method: read_buildbot_config 01:41:26 INFO - Using buildbot properties: 01:41:26 INFO - { 01:41:26 INFO - "project": "", 01:41:26 INFO - "product": "firefox", 01:41:26 INFO - "script_repo_revision": "production", 01:41:26 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 01:41:26 INFO - "repository": "", 01:41:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 01:41:26 INFO - "buildid": "20160523171639", 01:41:26 INFO - "pgo_build": "False", 01:41:26 INFO - "basedir": "/builds/slave/test", 01:41:26 INFO - "buildnumber": 8, 01:41:26 INFO - "slavename": "t-yosemite-r7-0030", 01:41:26 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 01:41:26 INFO - "platform": "yosemite_r7", 01:41:26 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 01:41:26 INFO - "branch": "mozilla-central", 01:41:26 INFO - "repo_path": "mozilla-central", 01:41:26 INFO - "revision": "46fe2115d46a5bb40523b8466341d8f9a26e1bdf", 01:41:26 INFO - "stage_platform": "macosx64", 01:41:26 INFO - "builduid": "2df6f63a3d754fefa021eb2c65da7322", 01:41:26 INFO - "slavebuilddir": "test" 01:41:26 INFO - } 01:41:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 01:41:26 INFO - Running post-action listener: _resource_record_post_action 01:41:26 INFO - ##### 01:41:26 INFO - ##### Finished read-buildbot-config step (success) 01:41:26 INFO - ##### 01:41:26 INFO - ##### 01:41:26 INFO - ##### Running download-and-extract step. 01:41:26 INFO - ##### 01:41:26 INFO - Running pre-action listener: _resource_record_pre_action 01:41:26 INFO - Running main action method: download_and_extract 01:41:26 INFO - mkdir: /builds/slave/test/build/tests 01:41:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:26 INFO - https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 01:41:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 01:41:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 01:41:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 01:41:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 01:41:27 INFO - Downloaded 1199 bytes. 01:41:27 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 01:41:27 INFO - Using the following test package requirements: 01:41:27 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 01:41:27 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:27 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 01:41:27 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:27 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 01:41:27 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:27 INFO - u'jsshell-mac.zip'], 01:41:27 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:27 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 01:41:27 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 01:41:27 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:27 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 01:41:27 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:27 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 01:41:27 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:27 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 01:41:27 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:27 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 01:41:27 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 01:41: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')]} 01:41:27 INFO - https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:41:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:41:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:41:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 01:41:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 01:41:28 INFO - Downloaded 18630520 bytes. 01:41:28 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'] 01:41:28 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 01:41:32 INFO - Return code: 0 01:41:32 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 01:41: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')]} 01:41:32 INFO - https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:41:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:41:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:41:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 01:41:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 01:41:37 INFO - Downloaded 18630520 bytes. 01:41: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'] 01:41: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 01:41:40 INFO - Return code: 0 01:41:40 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')]} 01:41:40 INFO - https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 01:41:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 01:41:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 01:41:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 01:41:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 01:41:41 INFO - Downloaded 10840359 bytes. 01:41:41 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'] 01:41:41 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 01:41:41 INFO - Return code: 0 01:41: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')]} 01:41:41 INFO - https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 01:41:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:41:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:41:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 01:41:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 01:41:46 INFO - Downloaded 110231948 bytes. 01:41:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:41:46 INFO - mkdir: /builds/slave/test/properties 01:41:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:41:46 INFO - Writing to file /builds/slave/test/properties/build_url 01:41:46 INFO - Contents: 01:41:46 INFO - build_url:https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:41:47 INFO - Running post-action listener: _resource_record_post_action 01:41:47 INFO - Running post-action listener: set_extra_try_arguments 01:41:47 INFO - ##### 01:41:47 INFO - ##### Finished download-and-extract step (success) 01:41:47 INFO - ##### 01:41:47 INFO - ##### 01:41:47 INFO - ##### Running populate-webroot step. 01:41:47 INFO - ##### 01:41:47 INFO - Running pre-action listener: _resource_record_pre_action 01:41:47 INFO - Running main action method: populate_webroot 01:41:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:41:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:41:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:41:47 INFO - '--tppagecycles', 01:41:47 INFO - '7'], 01:41:47 INFO - 'tests': ['tsvgm']}}, 01:41:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:41:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:41:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:41:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:41:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:41:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:41:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:47 INFO - 'talos_options': ['--disable-e10s'], 01:41:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:41:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:41:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:47 INFO - 'talos_options': ['--disable-e10s'], 01:41:47 INFO - 'tests': ['damp', 'tps']}, 01:41:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:47 INFO - 'tests': ['damp', 'tps']}, 01:41:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:41:47 INFO - 'tests': ['dromaeo_dom']}, 01:41:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:41:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:41:47 INFO - 'tests': ['a11yr', 01:41:47 INFO - 'ts_paint', 01:41:47 INFO - 'tpaint', 01:41:47 INFO - 'sessionrestore', 01:41:47 INFO - 'sessionrestore_no_auto_restore', 01:41:47 INFO - 'tabpaint']}, 01:41:47 INFO - 'other-e10s': {'tests': ['a11yr', 01:41:47 INFO - 'ts_paint', 01:41:47 INFO - 'tpaint', 01:41:47 INFO - 'sessionrestore', 01:41:47 INFO - 'sessionrestore_no_auto_restore', 01:41:47 INFO - 'tabpaint']}, 01:41:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:41:47 INFO - 'ts_paint', 01:41:47 INFO - 'tpaint', 01:41:47 INFO - 'sessionrestore', 01:41:47 INFO - 'sessionrestore_no_auto_restore', 01:41:47 INFO - 'tabpaint']}, 01:41:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:41:47 INFO - 'ts_paint', 01:41:47 INFO - 'tpaint', 01:41:47 INFO - 'sessionrestore', 01:41:47 INFO - 'sessionrestore_no_auto_restore', 01:41:47 INFO - 'tabpaint']}, 01:41:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:41:47 INFO - 'tests': ['a11yr', 01:41:47 INFO - 'ts_paint', 01:41:47 INFO - 'tpaint', 01:41:47 INFO - 'sessionrestore', 01:41:47 INFO - 'sessionrestore_no_auto_restore', 01:41:47 INFO - 'tabpaint']}, 01:41:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:41:47 INFO - 'tests': ['a11yr', 01:41:47 INFO - 'ts_paint', 01:41:47 INFO - 'tpaint', 01:41:47 INFO - 'sessionrestore', 01:41:47 INFO - 'sessionrestore_no_auto_restore', 01:41:47 INFO - 'tabpaint']}, 01:41:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:41:47 INFO - 'tests': ['tsvgx', 01:41:47 INFO - 'tsvgr_opacity', 01:41:47 INFO - 'tart', 01:41:47 INFO - 'tscrollx', 01:41:47 INFO - 'cart']}, 01:41:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:41:47 INFO - 'tsvgr_opacity', 01:41:47 INFO - 'tart', 01:41:47 INFO - 'tscrollx', 01:41:47 INFO - 'cart']}, 01:41:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:47 INFO - 'talos_options': ['--disable-e10s'], 01:41:47 INFO - 'tests': ['tp5o']}, 01:41:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:47 INFO - 'tests': ['tp5o']}, 01:41:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:47 INFO - 'talos_options': ['--disable-e10s', 01:41:47 INFO - '--xperf_path', 01:41:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:41:47 INFO - 'tests': ['tp5n']}, 01:41:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:41:47 INFO - 'talos_options': ['--xperf_path', 01:41:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:41:47 INFO - 'tests': ['tp5n']}}, 01:41:47 INFO - 'talos.zip': {'path': '', 01:41:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:41:47 INFO - Running post-action listener: _resource_record_post_action 01:41:47 INFO - ##### 01:41:47 INFO - ##### Finished populate-webroot step (success) 01:41:47 INFO - ##### 01:41:47 INFO - ##### 01:41:47 INFO - ##### Running create-virtualenv step. 01:41:47 INFO - ##### 01:41:47 INFO - Running pre-action listener: _resource_record_pre_action 01:41:47 INFO - Running main action method: create_virtualenv 01:41:47 INFO - Creating virtualenv /builds/slave/test/build/venv 01:41: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 01:41:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:41:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:41:47 INFO - Using real prefix '/tools/python27' 01:41:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:41:48 INFO - Installing distribute.............................................................................................................................................................................................done. 01:41:51 INFO - Installing pip.................done. 01:41:51 INFO - Return code: 0 01:41:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:41: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')]} 01:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41: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')]} 01:41:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41: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 0x105792a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105798570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a60586690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105636d30>, '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 0x10545adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10562db20>, '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.BZNpbYMdUk/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.aSY25GHG9W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 01:41: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 01:41: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 01:41:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BZNpbYMdUk/Render', 01:41:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:51 INFO - 'HOME': '/Users/cltbld', 01:41:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:51 INFO - 'LOGNAME': 'cltbld', 01:41:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:51 INFO - 'MOZ_NO_REMOTE': '1', 01:41:51 INFO - 'NO_EM_RESTART': '1', 01:41:51 INFO - 'PAGER': '/bin/cat', 01:41:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:51 INFO - 'PWD': '/builds/slave/test', 01:41:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:51 INFO - 'SHELL': '/bin/bash', 01:41:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aSY25GHG9W/Listeners', 01:41:51 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 01:41:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:51 INFO - 'USER': 'cltbld', 01:41:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:51 INFO - 'XPC_FLAGS': '0x0', 01:41:51 INFO - 'XPC_SERVICE_NAME': '0', 01:41:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41: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 01:41:51 INFO - Cleaning up... 01:41:51 INFO - Return code: 0 01:41:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:41: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')]} 01:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41: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')]} 01:41:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41: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 0x105792a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105798570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a60586690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105636d30>, '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 0x10545adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10562db20>, '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.BZNpbYMdUk/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.aSY25GHG9W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 01:41: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 01:41: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 01:41:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BZNpbYMdUk/Render', 01:41:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:51 INFO - 'HOME': '/Users/cltbld', 01:41:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:51 INFO - 'LOGNAME': 'cltbld', 01:41:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:51 INFO - 'MOZ_NO_REMOTE': '1', 01:41:51 INFO - 'NO_EM_RESTART': '1', 01:41:51 INFO - 'PAGER': '/bin/cat', 01:41:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:51 INFO - 'PWD': '/builds/slave/test', 01:41:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:51 INFO - 'SHELL': '/bin/bash', 01:41:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aSY25GHG9W/Listeners', 01:41:51 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 01:41:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:51 INFO - 'USER': 'cltbld', 01:41:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:51 INFO - 'XPC_FLAGS': '0x0', 01:41:51 INFO - 'XPC_SERVICE_NAME': '0', 01:41:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:52 INFO - Downloading/unpacking psutil>=3.1.1 01:41:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:41:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:41:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:41:55 INFO - Installing collected packages: psutil 01:41:55 INFO - Running setup.py install for psutil 01:41:55 INFO - building 'psutil._psutil_osx' extension 01:41: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_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 01:41: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 01:41:56 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 01:41:56 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 01:41:56 INFO - building 'psutil._psutil_posix' extension 01:41:56 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 01:41:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:41:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:41:56 INFO - ^ 01:41:56 INFO - 1 warning generated. 01:41:56 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 01:41:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:41:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:41:56 INFO - Successfully installed psutil 01:41:56 INFO - Cleaning up... 01:41:56 INFO - Return code: 0 01:41:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:41: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')]} 01:41:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41: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')]} 01:41:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41: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 0x105792a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105798570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a60586690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105636d30>, '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 0x10545adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10562db20>, '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.BZNpbYMdUk/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.aSY25GHG9W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 01:41: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 01:41: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 01:41:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BZNpbYMdUk/Render', 01:41:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:56 INFO - 'HOME': '/Users/cltbld', 01:41:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:56 INFO - 'LOGNAME': 'cltbld', 01:41:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:56 INFO - 'MOZ_NO_REMOTE': '1', 01:41:56 INFO - 'NO_EM_RESTART': '1', 01:41:56 INFO - 'PAGER': '/bin/cat', 01:41:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:56 INFO - 'PWD': '/builds/slave/test', 01:41:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:56 INFO - 'SHELL': '/bin/bash', 01:41:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aSY25GHG9W/Listeners', 01:41:56 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 01:41:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:56 INFO - 'USER': 'cltbld', 01:41:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:56 INFO - 'XPC_FLAGS': '0x0', 01:41:56 INFO - 'XPC_SERVICE_NAME': '0', 01:41:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:41: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 01:41: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 01:41: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 01:41: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 01:41: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 01:41: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 01:41:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:41:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:41:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:41:59 INFO - Installing collected packages: mozsystemmonitor 01:41:59 INFO - Running setup.py install for mozsystemmonitor 01:41:59 INFO - Successfully installed mozsystemmonitor 01:41:59 INFO - Cleaning up... 01:41:59 INFO - Return code: 0 01:41:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:41: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')]} 01:41:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41: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')]} 01:41:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41: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 0x105792a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105798570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a60586690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105636d30>, '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 0x10545adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10562db20>, '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.BZNpbYMdUk/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.aSY25GHG9W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 01:41: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 01:41: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 01:41:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BZNpbYMdUk/Render', 01:41:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:59 INFO - 'HOME': '/Users/cltbld', 01:41:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:59 INFO - 'LOGNAME': 'cltbld', 01:41:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:59 INFO - 'MOZ_NO_REMOTE': '1', 01:41:59 INFO - 'NO_EM_RESTART': '1', 01:41:59 INFO - 'PAGER': '/bin/cat', 01:41:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:59 INFO - 'PWD': '/builds/slave/test', 01:41:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:59 INFO - 'SHELL': '/bin/bash', 01:41:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aSY25GHG9W/Listeners', 01:41:59 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 01:41:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:59 INFO - 'USER': 'cltbld', 01:41:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:59 INFO - 'XPC_FLAGS': '0x0', 01:41:59 INFO - 'XPC_SERVICE_NAME': '0', 01:41:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:59 INFO - Downloading/unpacking blobuploader==1.2.4 01:41: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 01:41: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 01:41: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 01:41: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 01:41: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 01:41: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 01:42:02 INFO - Downloading blobuploader-1.2.4.tar.gz 01:42:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:42:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:42: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 01:42: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 01:42: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 01:42: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 01:42: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 01:42: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 01:42:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:42:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:42:03 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 01:42:03 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 01:42:03 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 01:42:03 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 01:42:03 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 01:42:03 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 01:42:03 INFO - Downloading docopt-0.6.1.tar.gz 01:42:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:42:03 INFO - Installing collected packages: blobuploader, requests, docopt 01:42:03 INFO - Running setup.py install for blobuploader 01:42:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:42:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:42:04 INFO - Running setup.py install for requests 01:42:04 INFO - Running setup.py install for docopt 01:42:04 INFO - Successfully installed blobuploader requests docopt 01:42:04 INFO - Cleaning up... 01:42:04 INFO - Return code: 0 01:42:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:42:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:42:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:42:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:04 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 0x105792a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105798570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a60586690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105636d30>, '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 0x10545adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10562db20>, '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.BZNpbYMdUk/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.aSY25GHG9W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 01:42:04 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 01:42:04 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 01:42:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BZNpbYMdUk/Render', 01:42:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:04 INFO - 'HOME': '/Users/cltbld', 01:42:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:04 INFO - 'LOGNAME': 'cltbld', 01:42:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:04 INFO - 'MOZ_NO_REMOTE': '1', 01:42:04 INFO - 'NO_EM_RESTART': '1', 01:42:04 INFO - 'PAGER': '/bin/cat', 01:42:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:04 INFO - 'PWD': '/builds/slave/test', 01:42:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:04 INFO - 'SHELL': '/bin/bash', 01:42:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aSY25GHG9W/Listeners', 01:42:04 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 01:42:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:04 INFO - 'USER': 'cltbld', 01:42:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:04 INFO - 'XPC_FLAGS': '0x0', 01:42:04 INFO - 'XPC_SERVICE_NAME': '0', 01:42:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:42:05 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-hNy9_N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:42:05 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-PIrBs0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:42:05 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-KIVCev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:42:05 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-4N_UbB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:42:05 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-c6FaNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:42:05 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-JQbqFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:42:05 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-UFQYyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:42:05 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-_ATgIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:42:05 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-YVyiHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:42:05 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-7XJ4eW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:42:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:42:06 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-tLcJ8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:42:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:42:06 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-c5eTPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:42:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:42:06 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-txBTsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:42:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:42:06 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-6pkTFL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:42:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:42:06 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-zGcUN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:42:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:42:06 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-FYhp3I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:42:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:42:06 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-gdxLLK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:42:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:42:06 INFO - Running setup.py install for manifestparser 01:42:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:42:06 INFO - Running setup.py install for mozcrash 01:42:07 INFO - Running setup.py install for mozdebug 01:42:07 INFO - Running setup.py install for mozdevice 01:42:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:42:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:42:07 INFO - Running setup.py install for mozfile 01:42:07 INFO - Running setup.py install for mozhttpd 01:42:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:42:07 INFO - Running setup.py install for mozinfo 01:42:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:42:07 INFO - Running setup.py install for mozInstall 01:42:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:42:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:42:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:42:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:42:07 INFO - Running setup.py install for mozleak 01:42:08 INFO - Running setup.py install for mozlog 01:42:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:42:08 INFO - Running setup.py install for moznetwork 01:42:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:42:08 INFO - Running setup.py install for mozprocess 01:42:08 INFO - Running setup.py install for mozprofile 01:42:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:42:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:42:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:42:08 INFO - Running setup.py install for mozrunner 01:42:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:42:08 INFO - Running setup.py install for mozscreenshot 01:42:09 INFO - Running setup.py install for moztest 01:42:09 INFO - Running setup.py install for mozversion 01:42:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:42:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:42:09 INFO - Cleaning up... 01:42:09 INFO - Return code: 0 01:42:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:42:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:42:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:42:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:09 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 0x105792a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105798570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a60586690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105636d30>, '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 0x10545adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10562db20>, '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.BZNpbYMdUk/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.aSY25GHG9W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 01:42:09 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 01:42:09 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 01:42:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BZNpbYMdUk/Render', 01:42:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:09 INFO - 'HOME': '/Users/cltbld', 01:42:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:09 INFO - 'LOGNAME': 'cltbld', 01:42:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:09 INFO - 'MOZ_NO_REMOTE': '1', 01:42:09 INFO - 'NO_EM_RESTART': '1', 01:42:09 INFO - 'PAGER': '/bin/cat', 01:42:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:09 INFO - 'PWD': '/builds/slave/test', 01:42:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:09 INFO - 'SHELL': '/bin/bash', 01:42:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aSY25GHG9W/Listeners', 01:42:09 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 01:42:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:09 INFO - 'USER': 'cltbld', 01:42:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:09 INFO - 'XPC_FLAGS': '0x0', 01:42:09 INFO - 'XPC_SERVICE_NAME': '0', 01:42:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:42:09 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-c1kI97-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:42:09 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)) 01:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:42:09 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-3iPNNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:42:09 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)) 01:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:42:09 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-8ktvNZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:42:09 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)) 01:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:42:09 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-j8Zsl6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:42:10 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)) 01:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:42:10 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-AWdz8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:42:10 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)) 01:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:42:10 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-clI8Jy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:42:10 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)) 01:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:42:10 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-d6fkv6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:42:10 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)) 01:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:42:10 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-z0dZ6q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:42:10 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)) 01:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:42:10 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-Ara2ry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:42:10 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)) 01:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:42:10 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-wanLo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:42:10 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)) 01:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:42:10 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-JZXZ7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:42:10 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)) 01:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:42:10 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-KHT6NG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:42:10 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)) 01:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:42:10 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-9Dk_6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:42:11 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)) 01:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:42:11 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-8Fm3Pl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:42:11 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)) 01:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:42:11 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-aHPkUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:42:11 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)) 01:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:42:11 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-EwUicY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:42:11 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)) 01:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:42:11 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-0l5tAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:42:11 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)) 01:42:11 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)) 01:42:11 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)) 01:42:11 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)) 01:42:11 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)) 01:42:11 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)) 01:42:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:42:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:13 INFO - Downloading blessings-1.6.tar.gz 01:42:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:42:13 INFO - Installing collected packages: blessings 01:42:13 INFO - Running setup.py install for blessings 01:42:14 INFO - Successfully installed blessings 01:42:14 INFO - Cleaning up... 01:42:14 INFO - Return code: 0 01:42:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:42:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:42:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:42:14 INFO - Reading from file tmpfile_stdout 01:42:14 INFO - Current package versions: 01:42:14 INFO - blessings == 1.6 01:42:14 INFO - blobuploader == 1.2.4 01:42:14 INFO - docopt == 0.6.1 01:42:14 INFO - manifestparser == 1.1 01:42:14 INFO - mozInstall == 1.12 01:42:14 INFO - mozcrash == 0.17 01:42:14 INFO - mozdebug == 0.1 01:42:14 INFO - mozdevice == 0.48 01:42:14 INFO - mozfile == 1.2 01:42:14 INFO - mozhttpd == 0.7 01:42:14 INFO - mozinfo == 0.9 01:42:14 INFO - mozleak == 0.1 01:42:14 INFO - mozlog == 3.2 01:42:14 INFO - moznetwork == 0.27 01:42:14 INFO - mozprocess == 0.22 01:42:14 INFO - mozprofile == 0.28 01:42:14 INFO - mozrunner == 6.11 01:42:14 INFO - mozscreenshot == 0.1 01:42:14 INFO - mozsystemmonitor == 0.3 01:42:14 INFO - moztest == 0.7 01:42:14 INFO - mozversion == 1.4 01:42:14 INFO - psutil == 3.1.1 01:42:14 INFO - requests == 1.2.3 01:42:14 INFO - wsgiref == 0.1.2 01:42:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:42:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:42:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:42:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:14 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 0x105792a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105798570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a60586690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105636d30>, '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 0x10545adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10562db20>, '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.BZNpbYMdUk/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.aSY25GHG9W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 01:42:14 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 01:42:14 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 01:42:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BZNpbYMdUk/Render', 01:42:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:14 INFO - 'HOME': '/Users/cltbld', 01:42:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:14 INFO - 'LOGNAME': 'cltbld', 01:42:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:14 INFO - 'MOZ_NO_REMOTE': '1', 01:42:14 INFO - 'NO_EM_RESTART': '1', 01:42:14 INFO - 'PAGER': '/bin/cat', 01:42:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:14 INFO - 'PWD': '/builds/slave/test', 01:42:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:14 INFO - 'SHELL': '/bin/bash', 01:42:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aSY25GHG9W/Listeners', 01:42:14 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 01:42:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:14 INFO - 'USER': 'cltbld', 01:42:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:14 INFO - 'XPC_FLAGS': '0x0', 01:42:14 INFO - 'XPC_SERVICE_NAME': '0', 01:42:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:14 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)) 01:42:14 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)) 01:42:14 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)) 01:42:14 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)) 01:42:14 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)) 01:42:14 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)) 01:42:14 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)) 01:42:14 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)) 01:42:14 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)) 01:42:14 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)) 01:42:14 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)) 01:42:14 INFO - Cleaning up... 01:42:14 INFO - Return code: 0 01:42:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:42:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:42:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:42:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:14 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 0x105792a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105798570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a60586690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105636d30>, '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 0x10545adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10562db20>, '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.BZNpbYMdUk/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.aSY25GHG9W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 01:42:14 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 01:42:14 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 01:42:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BZNpbYMdUk/Render', 01:42:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:14 INFO - 'HOME': '/Users/cltbld', 01:42:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:14 INFO - 'LOGNAME': 'cltbld', 01:42:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:14 INFO - 'MOZ_NO_REMOTE': '1', 01:42:14 INFO - 'NO_EM_RESTART': '1', 01:42:14 INFO - 'PAGER': '/bin/cat', 01:42:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:14 INFO - 'PWD': '/builds/slave/test', 01:42:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:14 INFO - 'SHELL': '/bin/bash', 01:42:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aSY25GHG9W/Listeners', 01:42:14 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 01:42:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:14 INFO - 'USER': 'cltbld', 01:42:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:14 INFO - 'XPC_FLAGS': '0x0', 01:42:14 INFO - 'XPC_SERVICE_NAME': '0', 01:42:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:15 INFO - Downloading/unpacking jsonschema 01:42:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:42:17 INFO - Downloading/unpacking functools32 (from jsonschema) 01:42: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 01:42: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 01:42: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 01:42: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 01:42: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 01:42: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 01:42:17 INFO - Downloading functools32-3.2.3-2.tar.gz 01:42:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:42:18 INFO - warning: no files found matching '*.txt' 01:42:18 INFO - no previously-included directories found matching 'build' 01:42:18 INFO - no previously-included directories found matching 'dist' 01:42:18 INFO - no previously-included directories found matching '.git*' 01:42:18 INFO - Installing collected packages: jsonschema, functools32 01:42:18 INFO - Running setup.py install for functools32 01:42:18 INFO - warning: no files found matching '*.txt' 01:42:18 INFO - no previously-included directories found matching 'build' 01:42:18 INFO - no previously-included directories found matching 'dist' 01:42:18 INFO - no previously-included directories found matching '.git*' 01:42:18 INFO - Successfully installed jsonschema functools32 01:42:18 INFO - Cleaning up... 01:42:18 INFO - Return code: 0 01:42:18 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 01:42:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:42:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:42:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105792a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105798570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a60586690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105636d30>, '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 0x10545adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10562db20>, '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.BZNpbYMdUk/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.aSY25GHG9W/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 01:42:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 01:42:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 01:42:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BZNpbYMdUk/Render', 01:42:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:18 INFO - 'HOME': '/Users/cltbld', 01:42:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:18 INFO - 'LOGNAME': 'cltbld', 01:42:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:18 INFO - 'MOZ_NO_REMOTE': '1', 01:42:18 INFO - 'NO_EM_RESTART': '1', 01:42:18 INFO - 'PAGER': '/bin/cat', 01:42:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:18 INFO - 'PWD': '/builds/slave/test', 01:42:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:18 INFO - 'SHELL': '/bin/bash', 01:42:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aSY25GHG9W/Listeners', 01:42:18 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 01:42:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:18 INFO - 'USER': 'cltbld', 01:42:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:18 INFO - 'XPC_FLAGS': '0x0', 01:42:18 INFO - 'XPC_SERVICE_NAME': '0', 01:42:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:18 INFO - Downloading/unpacking flake8 01:42:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:21 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 01:42:21 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 01:42:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:21 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 01:42:21 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 01:42:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:21 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 01:42:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 01:42:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:42:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:42:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:42:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:42:22 INFO - no previously-included directories found matching 'docs/_build' 01:42:22 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 01:42:22 INFO - Running setup.py install for pep8 01:42:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:42:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:42:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:42:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:42:22 INFO - no previously-included directories found matching 'docs/_build' 01:42:22 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 01:42:22 INFO - Successfully installed flake8 mccabe pyflakes pep8 01:42:22 INFO - Cleaning up... 01:42:22 INFO - Return code: 0 01:42:22 INFO - Running post-action listener: _resource_record_post_action 01:42:22 INFO - Running post-action listener: _start_resource_monitoring 01:42:22 INFO - Starting resource monitoring. 01:42:22 INFO - ##### 01:42:22 INFO - ##### Finished create-virtualenv step (success) 01:42:22 INFO - ##### 01:42:22 INFO - ##### 01:42:22 INFO - ##### Running install step. 01:42:22 INFO - ##### 01:42:22 INFO - Running pre-action listener: _resource_record_pre_action 01:42:22 INFO - Running main action method: install 01:42:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:42:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:42:23 INFO - Reading from file tmpfile_stdout 01:42:23 INFO - Detecting whether we're running mozinstall >=1.0... 01:42:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:42:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:42:23 INFO - Reading from file tmpfile_stdout 01:42:23 INFO - Output received: 01:42:23 INFO - Usage: mozinstall [options] installer 01:42:23 INFO - Options: 01:42:23 INFO - -h, --help show this help message and exit 01:42:23 INFO - -d DEST, --destination=DEST 01:42:23 INFO - Directory to install application into. [default: 01:42:23 INFO - "/builds/slave/test"] 01:42:23 INFO - --app=APP Application being installed. [default: firefox] 01:42:23 INFO - mkdir: /builds/slave/test/build/application 01:42:23 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'] 01:42:23 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 01:42:56 INFO - Reading from file tmpfile_stdout 01:42:56 INFO - Output received: 01:42:56 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 01:42:56 INFO - Running post-action listener: _resource_record_post_action 01:42:56 INFO - ##### 01:42:56 INFO - ##### Finished install step (success) 01:42:56 INFO - ##### 01:42:56 INFO - ##### 01:42:56 INFO - ##### Running run-tests step. 01:42:56 INFO - ##### 01:42:56 INFO - Running pre-action listener: _resource_record_pre_action 01:42:56 INFO - Running main action method: run_tests 01:42:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:42:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:42:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:42:56 INFO - 2016-05-24 01:42:56.930 screenresolution[1643:7966] starting screenresolution argv=screenresolution get 01:42:56 INFO - 2016-05-24 01:42:56.932 screenresolution[1643:7966] Display 0: 1600x1200x32@60 01:42:56 INFO - 2016-05-24 01:42:56.938 screenresolution[1644:7968] starting screenresolution argv=screenresolution list 01:42:56 INFO - Available Modes on Display 0 01:42:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:42:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:42:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:42:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:42:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:42:57 INFO - Intel Iris: 01:42:57 INFO - Chipset Model: Intel Iris 01:42:57 INFO - Type: GPU 01:42:57 INFO - Bus: Built-In 01:42:57 INFO - VRAM (Dynamic, Max): 1536 MB 01:42:57 INFO - Vendor: Intel (0x8086) 01:42:57 INFO - Device ID: 0x0a2e 01:42:57 INFO - Revision ID: 0x0009 01:42:57 INFO - Displays: 01:42:57 INFO - Display: 01:42:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:42:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:42:57 INFO - Display Serial Number: 1600x1200 60 01:42:57 INFO - Main Display: Yes 01:42:57 INFO - Mirror: Off 01:42:57 INFO - Online: Yes 01:42:57 INFO - Rotation: Supported 01:42:57 INFO - Return code: 0 01:42:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:42:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:42:57 INFO - Python 2.7.3 01:42:57 INFO - Return code: 0 01:42:57 INFO - grabbing minidump binary from tooltool 01:42:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:42:57 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 0x105636d30>, '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 0x10545adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10562db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:42:57 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 01:42:57 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 01:42:57 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 01:42:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:42:57 INFO - Return code: 0 01:42:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:42:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:42:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:42:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:42:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:42:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 01:42:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 01:42:58 INFO - Return code: 0 01:42:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0030', '--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 01:42:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0030 --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 01:42:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BZNpbYMdUk/Render', 01:42:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:58 INFO - 'HOME': '/Users/cltbld', 01:42:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:58 INFO - 'LOGNAME': 'cltbld', 01:42:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:42:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:42:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:58 INFO - 'MOZ_NO_REMOTE': '1', 01:42:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:42:58 INFO - 'NO_EM_RESTART': '1', 01:42:58 INFO - 'PAGER': '/bin/cat', 01:42:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:58 INFO - 'PWD': '/builds/slave/test', 01:42:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:42:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:58 INFO - 'SHELL': '/bin/bash', 01:42:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aSY25GHG9W/Listeners', 01:42:58 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 01:42:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:58 INFO - 'USER': 'cltbld', 01:42:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:58 INFO - 'XPC_FLAGS': '0x0', 01:42:58 INFO - 'XPC_SERVICE_NAME': '0', 01:42:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0030', '--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 01:42:58 INFO - mozversion application_buildid: 20160523171639 01:42:58 INFO - mozversion application_changeset: 46fe2115d46a5bb40523b8466341d8f9a26e1bdf 01:42:58 INFO - mozversion application_display_name: Nightly 01:42:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:42:58 INFO - mozversion application_name: Firefox 01:42:58 INFO - mozversion application_remotingname: firefox 01:42:58 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 01:42:58 INFO - mozversion application_vendor: Mozilla 01:42:58 INFO - mozversion application_version: 49.0a1 01:42:58 INFO - mozversion platform_buildid: 20160523171639 01:42:58 INFO - mozversion platform_changeset: 46fe2115d46a5bb40523b8466341d8f9a26e1bdf 01:42:58 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 01:42:58 INFO - mozversion platform_version: 49.0a1 01:42:58 INFO - using testdate: 1464079378 01:42:58 INFO - actual date: 1464079378 01:42:58 INFO - starting webserver on 'localhost:49275' 01:42:58 INFO - SUITE-START | Running 2 tests 01:42:58 INFO - TEST-START | dromaeo_css 01:42:58 INFO - operating with platform_type : mac_ 01:42:58 INFO - Initialising browser for dromaeo_css test... 01:42:58 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpcUsien/profile http://localhost:49275/getInfo.html) 01:43:00 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 01:43:00 INFO - PROCESS | 1662 | colorDepth:24 01:43:00 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 01:43:00 INFO - PROCESS | 1662 | __metrics 01:43:00 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" 01:43:00 ERROR - PROCESS | 1662 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 01:43:01 INFO - TEST-INFO | 1662: exit 0 01:43:01 INFO - Browser initialized. 01:43:01 INFO - Running cycle 1/1 for dromaeo_css test... 01:43:01 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpcUsien/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:43:13 INFO - PROCESS | 1664 | RSS: Main: 239054848 01:43:13 INFO - PROCESS | 1664 | 01:44:05 INFO - PROCESS | 1664 | 1464079445126 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] 01:46:15 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 01:46:16 INFO - PROCESS | 1664 | RSS: Main: 341864448 01:46:16 INFO - PROCESS | 1664 | 01:49:19 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 01:49:20 INFO - PROCESS | 1664 | RSS: Main: 370098176 01:49:20 INFO - PROCESS | 1664 | 01:52:22 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 01:52:22 INFO - PROCESS | 1664 | RSS: Main: 368029696 01:52:22 INFO - PROCESS | 1664 | 01:55:25 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 01:55:25 INFO - PROCESS | 1664 | RSS: Main: 369467392 01:55:25 INFO - PROCESS | 1664 | 01:58:28 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 01:58:29 INFO - PROCESS | 1664 | RSS: Main: 344825856 01:58:29 INFO - PROCESS | 1664 | 02:01:32 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 02:01:32 INFO - PROCESS | 1664 | RSS: Main: 401448960 02:01:32 INFO - PROCESS | 1664 | 02:01:32 INFO - PROCESS | 1664 | __start_tp_report 02:01:32 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 02:01:32 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 02:01:32 INFO - PROCESS | 1664 | |i|pagename|runs| 02:01:32 INFO - PROCESS | 1664 | |0;dojo.html;3465.4108801503726;3474.5135681004654;3486.7036301914336;3501.45727412251;3531.0642679689886;4696.483386827446;5649.4068122847075;5654.519365853897;5759.510441612463;5780.364159942403;4721.9055618887605;4845.709257444556;5681.431856814316;5733.971330143354;5800.941990580103;4614.192516309641;5212.895742085156;5231.032259032072;5307.787688492455;5313.203019547061;5266.52601265886;5326.493983071621;5388.91916621251;5429.6742195468205;5461.0716178249595;5375.193720941851;5385.515303622654;5416.268803711508;5417.674939503623;5457.536109430703;5190.4031036430915;5250.949810038008;5263.105272103751;5267.104592219293;5371.623986320959;5226.137687281594;5278.630495865291;5280.2079688046715;5291.3385826771655;5312.389075256357;5644.379118296984;5693.629914055573;5747.655140691572;5763.991301522216;5777.682227477487;6087.190849751468;6170.136180934673;6189.9381006189815;6253.781117660906;6262.749490020394;6262.71817768197;6292.244930608311;6349;6363.618182908994;6379.904301435482;5855.151003104568;5856.824295271149;5859.062549991981;5873.500752436048;5924.792632257893;6265.498760099181;6280.623162610258;6342.175517182736;6345.841353966188;6357.300696923335;6242.282137554149;6253.78111766086;6256.593321434092;6265.310815810257;6271.090691849657;6180.6909654517995;6289;6336.936630633727;6341.207349081365;6359.332270111664;6185.443310102022;6278.905816412757;6286.779962701375;6345.873082538324;6349.111124442582;6272.153259309935;6354.142190804275;6354.618722876642;6364.172657554581;6367.108604795332;4862.95137048632;4896.2410826321975;4987.850364489072;5011.924821127686;5060.291559181717;4424.137293227854;4427.026054268056;4435.223835828717;4456.888577785581;4479.865604031885;4726.645501587326;5809.2738407699035;5811.157382179645;5876.324222714443;5883.823485295448;6270.184875966095;6275.560710750204;6310.148130002391;6321.399467050623;6322.241331040304;5652.63257888236;5773.538116950718;5811.535077193815;5834.533237341003;5851.151583020438;5427.1045277528765;5434.510894019557;5483.040467918098;5494.80768173112;5524.06090964537;6419.605499103389;6453.128827608213;6475.255345635313;6481.578697384655;6482.643454610042;6221.9377806221355;6295.716692748746;6296.212973378328;6334.398232167937;6360.618362898056;5356;6305.747770089145;6368.395002474757;6409.615423074631;6457.354264573693;5209.4009188943755;6273.162253144094;6292.433680968735;6302.086197501429;6323.747050117944;5097.541221290102;6224.346443623354;6314.715837787402;6349.301576826638;6351.587146835441;4732.290156476556;5540.919520693426;6140.754369825157;6200.410960958701;6202.162708034538;6054.939450605438;6188.0717892314715;6194.690265486617;6199.473044791243;6204.255489341308;5903.970480147572;6069.666168360783;6078.574499784972;6088.665123418255;6123.050927106306;6160;6200.844978875564;6213.658248796179;6230.563860529719;6231.688415579293;6118.908216376669;6127.540434467343;6138.969305153446;6159.692015399122;6170.259568851767 02:01:32 INFO - PROCESS | 1664 | |1;ext.html;475.94526629437627;483.4005832767367;484.5614718679594;487.71712406804056;507.13786562843177;31716.68283317166;42516;43719.78140109303;43721.34417983725;43771.90570235738;45235.86910327235;45803.54196458034;45882.770586147104;45972.540274597246;46302.305465418045;8443.577821108951;9447;9614.711558653216;9664.661736839213;9696;8631.050584435707;11513.136514761427;11672.649820505398;11755.647330580054;11999.280043197392;11861.347625880573;11916.642500724949;11922.986546143587;11965.282083075;11973.341466219354;8539.658413663446;11528.827067593993;11576.24754390962;11676.649700508955;11775.470103845348;11746.236494627907;11839.881601183964;11850.525978960832;11856.347900865449;11865.52537898483;40815.387769183486;41539.7923010386;41826;41884;41941.580584194075;2946.381259935416;3105.5652208690803;3353.144948038259;3438.7420943429343;3732.5520937487677;28835.85582072076;29000.564991525145;29522.852385738148;29572.55641165384;29702.702972970208;2691.7981151413724;2695.9460810783676;2696.8381897086238;2705.7700095491905;2801.3836955869674;462969;515947;516907;517162.9999999962;517380.82619173324;65585.67207163933;66500;66509;66523.66738166279;66608.33391666021;35650.465243021375;36686.449703254475;36703.632963670025;38002.80998594989;68186.318136818;16691.49925502237;17105.572360691083;17235.3967611133;17299.913500432416;17330.480085597454;10439.530221139916;11832.763344733057;12048.397580120958;13015.804762928563;13108.475660973645;20564;20674.689879651818;20714.896425517774;20761.89619051895;20814.895925520577;6822.283660215705;7999.5600241986085;8082.757517274491;8116.837663246702;8133.170036741686;727.8980942667924;1045.1951996962296;1051.1433181956834;1053.7049626104704;1094.8795632480421;29205.561916571267;29243.268918276786;29794.95717649872;30019.69980300169;30100.84949575282;1021.1116328793996;1038.2317085356933;1065.6003998500562;1121.7980763462492;1226.8098444741079;13159.67100822487;14278;15265.542033739006;15274.465393711389;15441.613959650875;691428;698763;705418;705754;707451;61889.38110618926;62982.37017629765;62988.370116299164;63532.68233658894;63576.68211658912;1301.8502872169631;1422.4879043544522;1437.3100911562299;1448.608875603581;1466.8459811720043;30257.24356891095;30441.543376848924;30589;30951;31065.534016990216;53267.20099198438;53636.195457068956;54076;54305.45694543162;54512;397492;472135;478090;480107;480460;23423.297301080995;26323.73676263213;27037;27310.5903411445;27976;45631.77184114058;46402.30396543987;47102.76448617735;47122.293165601855;47818.5218147828;37242.8137859309;38660.80669596634;38677.80661096789;38681.03297417587;39638.60361396349;32778.50832237566;36911.41620086568;37926.62073379231;38038.80980595079;39069.60930390773;37694.8115259422;38217;38235.23529529449;38301;38400.80799595984;30711.539326910563;32758;32942.011739647845;33056;33274;24575.38561535976;24763.009479620614;24929.87535062313;25398;25460.236192914243 02:01:32 INFO - PROCESS | 1664 | |2;jquery.html;319.31188289236695;376.3677022602029;378.6459660217695;389.9434581985614;401.35382034923765;16438.75341869873;21258.255961041366;21319.147234110656;21370.038348274327;21873.890630546844;19866.40400787975;20499.07754151063;21435.571288574218;21466;21490;5149.76826042829;5778.6243894146955;5968.641881487125;5997.760089596411;6005.069214271794;5784.421557844203;5916.3787802280785;5936.376680448556;5962.552808539355;5964.582479226456;5812.273465816773;5872.677002764846;6174.876502469948;6177.104319873616;6186.041163619646;5783.103618939072;6139;6174.536909731788;6177.3822617738315;6186.84532886677;5930.525557955353;5934.397712617569;5950.047992321208;5978.40215978403;5984.9401505984815;13340.86659133406;13434.261115638636;13436.932815335958;13510.392032358568;13561.25413102279;2012.1347820437206;2071.5028393185676;2111.0289266937075;2354.564405584972;2609.595512695535;9223.262139028862;9408.43549387039;10034.698959031168;10143.543540540693;10188.388696678148;1605.710972025035;1666.2834980958182;1714.271434640273;1774.8225177482148;1814.0748218408576;96007;106504;118085;120164.59670806622;122281;10350.689479315633;10950.34297942126;11355.54577816878;11587.826182607268;11886.465109070112;14536.491222807152;14619.78070328946;14674;14675.77986330206;14685.706285874436;8497.320214382835;8570.828583428422;8588.656453741905;8591.140885911484;8739.951205855337;7000.964995174992;8434.789130271793;8518.23335899772;8523.48859068458;8621.00858401292;4470.821167153343;4480.439945006874;4529.9547004530195;4565.768210202872;4594.1730488512385;1851.4445666300055;1896.8103189680921;1897.5066482714594;1904.8190421909896;1912.4836294200766;597.6264834478451;710.4884483172108;770.7995921060565;903.9909600903907;953.8855337359561;17163.40240325492;19682.409527714193;19845.60308793816;20112.59774804496;20318.18727250923;891.6032365597247;909.2771246858763;929.3773171974658;978.9755256118511;1000.2548101664306;10458.320209186379;10667.626633067805;10769.407682577377;10829.458527073772;10839.945800271105;618714;620017;621284;630325;631239;104190.4790476058;131216;131246.34376828055;131830.34084829706;133743.33128334297;922.7462447826902;975.5853762151199;991.7058238998177;993.7267251505893;1000.3797645459862;16198.433054843024;17939.641207176046;17951.910240449237;17970;18183.636327273645;36601;46576;47396.7630161847;47650.285245720646;48406;73401.6329918347;74573.25426745664;75409.62295188708;76154.07174704972;76197;7806.477736841489;7970.1631328709655;7997.729162827961;8161.265486106042;8176.345892328718;7189.2810718927685;8755.387122901591;11249.25636526307;12131;12472.189307694971;10206.59173633076;10350.689479315633;10557.260991730505;10712.250142489951;10740.462976851282;10380.844287335545;10507.264491485523;10515.894841051797;10671.733206669895;10930.45347732626;10539;10788;11100.722481938015;11249.1563132767;11284.379359135326;7999.600019999093;8015.238552337519;8032.277095061472;8051.1546287641295;8074.919250807652;10248.590056397661;10596.576136954744;10823.67528974132;10847.945760271148;10860.402677852793 02:01:32 INFO - PROCESS | 1664 | |3;mootools.html;1353.6480515066091;1558.4467514032517;1584.6751415959725;1807.2861219818171;1956.696712009638;7896.92103078969;7897.605119744011;8686.348523860712;8897.777555561113;8897.911020889796;8627.439216450943;8731.083236260196;8864.73405797827;8874.20132188103;8888.155625215593;1974.7926467720897;2136.39112852837;2211.1818627107914;2291.6906217660653;2406.9398265043337;2415.7463466336044;2435.671184390111;2445.461998360358;2446.779789818916;2456.742042085582;2358.7641235876445;2383.7854593086618;2401.0755858994326;2401.5317013182434;2405.326508577601;2014.7307196466206;2094.1204694028543;2302.861828290308;2387.6060450025725;2390.4501964548076;1964.3223088034606;2379.5240951809533;2410.3612542676206;2414.38433199533;2419.1774796569252;8713;8744;8814.603342849587;8822.779430514225;8851.867221991675;5214.556762675177;5283.894322113575;5286.867828304285;5349.037173308803;5365.704886231256;3275.983620081908;3352.715019223369;3467.0465621954163;3517.278957813642;3521.1197200699826;846.0735494633428;894.4767311123001;983.6704703924194;984.9359791613522;1010.2019404670301;11607;11669.00813430851;11706.707332316675;11707;11765.411729413581;4548.476925153584;4654.25531914892;4698.295255711602;4727.57451829337;4731.881702957454;9944.25418093646;9944.701658950244;9972.202223822076;9998.450085245382;10000.249981251436;5232.790688372423;5260.92108618371;5266.183741520071;5266.552343050884;5268.762905669254;4434.33484977256;4592.977035114803;4765.571098601074;4818.4217893852965;4915.533024362751;3916.5104361954754;3961.564227934925;4027.335489644176;4058.1680755445063;4072.8778136655355;1261.3377976562194;1282.58316047285;1285.6785803549112;1296.623979046066;1300.4408104515148;787.4920676163957;879.4064006795516;901.0538934119148;935.050923312838;937.0722984245684;5608.635438696471;5760.942390576124;5853.151293062482;5899.882002359929;6029.427204415573;909.4452384045727;915.3135148638521;916.9541522923827;939.5490164720973;967.7435479597913;3823.1971286030243;3854.1520865409566;3897.9610203898164;3920.17676287977;3989.960100399017;10428.687139385831;10599.735006624896;10623.681289561171;10667.626633067805;10765.24643274979;10506.264561480622;10541.57833686644;10729.248952573402;10731.946340268249;10736.677899663022;7770.611469426506;7848.215178482106;8147.307478864363;8208.794780130425;8301.335893128535;5560.221568980265;5665.3201615806365;5672.602917795714;5684;5726.685032323096;9718.611255549698;9803.16673082796;10043.347182433119;10142.391456512632;10173.593056277667;8768.34237432208;9127.726168214964;9778.951105244712;10200.642977495752;10356.792864142524;6180.876382472416;6363.87272254543;6629.6353700545005;6863.450923926173;6917.688704008231;7906.683732650629;7983.2415920487465;7984.760457186294;8033.236842499953;8488.490690558587;6697.531172817856;7489.325960663348;7643.350315223268;7647.311741943252;7659.1192012917;7228.783136505913;7289.744858929912;7314.232005639331;7451.3293803557945;7454.776356709307;6805.149356330458;6896.000079988474;7041.647917603997;7050.964745176241;7075.080239568823;4748.430188377417;4808.9519104808505;4860.538248866352;4949.505049495022;5080.872978175576;4858.684185527929;4917.975410123069;4930.6795058321095;4972.005598880166;5173.0688476074665 02:01:32 INFO - PROCESS | 1664 | |4;prototype.html;278.6725597423029;292.1513004721285;301.8309746541936;311.117980525211;311.7365825877133;5467.5899307551945;5528.55771538277;5532.727472681286;5564;5589.329280486348;5011.047900898827;5030.560178313515;5367.731613419323;5452.945470545294;5455.890882182364;3938.8621398251066;3945.7040721945755;4109.568495307994;4117.649999750025;4155.667546596265;4093.713440059197;4145.689073319498;4155.252054630166;4172.08214192877;4186.97419132313;3469.8959031229106;4126.071633882368;4135.966008497876;4143.7513749175;4149.8962525936795;3438.965610343902;3647.6352364763575;3921.588233235512;4092.6930480214105;4138.503379594438;3190.9469874941215;3533.717302615785;3549.503069570262;3574.4459608760685;3599.982000090009;4323.546027667081;4760.738159401231;5179.808644011899;5207.062728708831;5278.97360513195;4603.562661547147;4718.882027949295;4749.978754567785;4778.713277203379;4803.567678908915;5651.784866253775;5664.971675141598;5718.942810571883;5747.913781293284;5750.45370689788;3995.003747189608;3999.8000099995174;4002.459667944819;4020.457238272825;4027.7784721840285;5085.7622646036;5646.402654778807;5914.669199430094;5991.580589358747;6009.549283803689;4028.4561584186345;4047.615476529725;4058.370952502367;4067.9389809152885;4076.9388459173138;4145.79271036447;4158.9376159357635;4166.437530933285;4170.4995400552125;4174.457320548309;4600.930986035212;4787.40157480315;4792.7124372537755;4798.880027999328;4816.783244753994;4692.882677933079;4881.609471242292;5744.655320680772;5747.540196784247;5785;1410.344189951657;1424.921629310377;1425.258865390012;1429.4568064135563;1435.2321507993381;819.4591569564059;869.8216865542548;887.7026196224208;888.1163242573551;897.2373482539789;4553.814169198803;5234.26720259164;5364.597655175799;5367.543758780469;5376.542993845567;5846.070796637099;5887.676177810262;5925.525957923442;5926.733297001645;5940.762369505258;4449.7997590108525;5134.743262836843;5392.892142157135;5414.40441551429;5430.321209848769;3052.290717198376;3066.5400189971683;3074.8155110692983;3079.276370053036;3091.9332830173553;4993.525615066563;6142.293636231801;6144.754209831557;6152.938470615326;6178.104174894705;5223.608229382811;6033.306169790442;6063.393660633989;6075.787347442818;6092.421219984094;5203;6136.422702464975;6147.354527774609;6150.292716337678;6160.507159427235;4563.3839431677625;5200.191896376066;5867.970660146672;5894.911576326273;5936.821895343147;4784.976075119602;4904.068227036852;5124;5351.090314646442;5723.59771855898;5140.3831540215415;5415.296011518478;5670.262865827416;6163.691815409122;6193.504519638328;3669.522962014921;3970.841166353429;3993.940090898697;4019.4878201124297;4035.91928161441;4840.588549973291;5261.605379596469;5698.686572238566;5939.46544810969;5952.196453478725;4698.530146985274;5362.115250983544;5745.770169193353;5800.6809625471005;5850.5027072699295;4760.78576464053;5124.949385375914;5316.415194328698;5328.536451306527;5764.942350576608;5065.594752419871;5078.7206703631655;5175.068487672255;5656.490915817596;5681.9431805681415;5028.472010438852;5411.891762164656;5523.28197334344;5577.9442205577425;5780.132980053026;5182.870428239325;5436.809711660073;5658.688772117573;5672.688002159921;5708.457696518824 02:01:32 INFO - PROCESS | 1664 | |5;yui.html;691.927347628499;694.163532942804;710.8613820305043;744.1925510820756;755.7506023012406;14901.105933643976;15219;15407.537773866788;15458.304376303066;15484.458043968463;14262.928685356559;14312.642183945407;14316.570502884904;14324.140551566914;15308.6938261235;892.321835405092;905.2260317428603;906.084854297158;906.2523418179995;907.4645958884257;867.119873328572;869.2958703450194;881.8412685716569;885.2033170146855;901.197933838884;928.16465181337;957.5355952363133;958.3674774648733;958.8513780364054;958.9136977672008;946.9810603787954;951.1772316945861;952.9237660987139;953.2850362228329;953.8712273843012;673.2829536543566;696.4045740891515;696.5681277607915;697.1842943755818;697.9127609048869;15002.69994600102;15199.392024319013;15224.771628425471;15231.543053708407;15240.695186096216;11869.703257418547;11988.940055299669;12100.455479503444;12352.949999250075;12670.429830657551;10789.352638841694;10861.1311095113;11052;11078.390688512129;11516.769664606745;3997.320455522568;4059.2084543514025;4091.345384738457;4130.029443080904;4134.131832315187;62247.37752622461;63803.60798933162;77997;82589.58705206496;84206.31587368279;9397.201237894855;9463.195628371666;9479.620815167316;9748.658796942073;9783.608655653836;20417.59164816725;20824.791752082587;20862.791372086387;20929.162833486793;21021;7409.110906691231;7453.217412171753;7486.962565187139;7508.2867127622785;7671.808204794813;3948.58539853317;4014.9197016059516;4024.597540245952;4079.7144199906315;4306.82772689089;3494.353544594258;3586.049696830342;3586.228960773446;3587.408077667208;3591.586967498771;585.844751140948;593.2880543348;620.6989610039127;625.3308959413384;632.6393955445352;509.943906170321;547.5400663442648;575.9164921086419;603.4629180029777;612.1582823617526;7004.614746189009;7338.596377199198;7985.520868747778;8169.591520423955;8250.216229458192;506.6073792810601;573.1746285349084;586.8503531599415;593.2199158107076;605.2555356911024;3601.8758521492746;3626.220362622049;3694.8152592370275;3797.734158608926;3920.9019774505864;52074.21888671673;71978.56042879113;79072.6046369776;88395.11604883998;95831.52084239674;61381;72292;73748;74789;76017;52597.474025260024;64067.35932640707;69607.65196173987;72057.63971180216;72758;8332.416730828783;8346.01433909925;8362;8422.915770842214;8582.0988796178;29187.85406072956;36482.8175859119;43907.12185756331;44820.87947801331;45296.547034529234;59447.10829337477;59869;60035.39964600298;60730.696346519755;62486.68756656188;10328.328658637165;10706;10914.945425272823;11035.174681381852;11195.664130076111;11769.646910592695;12159.331236782062;12203.20679155852;12298.569550065704;12507.876965520009;11168.497417616065;11280.26678265943;11684.591039313584;11743.88256117428;11772;9294.442333460014;10150.390976541432;11517.308961462339;11579.536818527165;11760.76478470443;9944.403335799876;9957.900420995697;11258.268212566158;11555.537778488766;12084.577039803564;9226.723198304062;9270.490123043297;9419.481928493724;9435.481048542397;9456.2907781918;8989.280857531512;9368.278499163633;9541.093596108358;9543.570539325887;9574.234061275221 02:01:32 INFO - PROCESS | 1664 | __end_tp_report 02:01:32 INFO - PROCESS | 1664 | __start_cc_report 02:01:32 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1125 02:01:32 INFO - PROCESS | 1664 | __end_cc_report 02:01:32 INFO - PROCESS | 1664 | __startTimestamp1464080492545__endTimestamp 02:01:32 INFO - PROCESS | 1664 | 02:01:32 INFO - PROCESS | 1664 | ------- Summary: start ------- 02:01:32 INFO - PROCESS | 1664 | Number of tests: 6 02:01:32 INFO - PROCESS | 1664 | 02:01:32 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5771.76 Median:6069.67 stddev:668.50 (11.0%) stddev-sans-first:645.60 02:01:32 INFO - PROCESS | 1664 | Values: 3465.4 3474.5 3486.7 3501.5 3531.1 4696.5 5649.4 5654.5 5759.5 5780.4 4721.9 4845.7 5681.4 5734.0 5800.9 4614.2 5212.9 5231.0 5307.8 5313.2 5266.5 5326.5 5388.9 5429.7 5461.1 5375.2 5385.5 5416.3 5417.7 5457.5 5190.4 5250.9 5263.1 5267.1 5371.6 5226.1 5278.6 5280.2 5291.3 5312.4 5644.4 5693.6 5747.7 5764.0 5777.7 6087.2 6170.1 6189.9 6253.8 6262.7 6262.7 6292.2 6349.0 6363.6 6379.9 5855.2 5856.8 5859.1 5873.5 5924.8 6265.5 6280.6 6342.2 6345.8 6357.3 6242.3 6253.8 6256.6 6265.3 6271.1 6180.7 6289.0 6336.9 6341.2 6359.3 6185.4 6278.9 6286.8 6345.9 6349.1 6272.2 6354.1 6354.6 6364.2 6367.1 4863.0 4896.2 4987.9 5011.9 5060.3 4424.1 4427.0 4435.2 4456.9 4479.9 4726.6 5809.3 5811.2 5876.3 5883.8 6270.2 6275.6 6310.1 6321.4 6322.2 5652.6 5773.5 5811.5 5834.5 5851.2 5427.1 5434.5 5483.0 5494.8 5524.1 6419.6 6453.1 6475.3 6481.6 6482.6 6221.9 6295.7 6296.2 6334.4 6360.6 5356.0 6305.7 6368.4 6409.6 6457.4 5209.4 6273.2 6292.4 6302.1 6323.7 5097.5 6224.3 6314.7 6349.3 6351.6 4732.3 5540.9 6140.8 6200.4 6202.2 6054.9 6188.1 6194.7 6199.5 6204.3 5904.0 6069.7 6078.6 6088.7 6123.1 6160.0 6200.8 6213.7 6230.6 6231.7 6118.9 6127.5 6139.0 6159.7 6170.3 02:01:32 INFO - PROCESS | 1664 | 02:01:32 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:69340.59 Median:18313.57 stddev:151561.29 (827.6%) stddev-sans-first:151898.26 02:01:32 INFO - PROCESS | 1664 | Values: 475.9 483.4 484.6 487.7 507.1 31716.7 42516.0 43719.8 43721.3 43771.9 45235.9 45803.5 45882.8 45972.5 46302.3 8443.6 9447.0 9614.7 9664.7 9696.0 8631.1 11513.1 11672.6 11755.6 11999.3 11861.3 11916.6 11923.0 11965.3 11973.3 8539.7 11528.8 11576.2 11676.6 11775.5 11746.2 11839.9 11850.5 11856.3 11865.5 40815.4 41539.8 41826.0 41884.0 41941.6 2946.4 3105.6 3353.1 3438.7 3732.6 28835.9 29000.6 29522.9 29572.6 29702.7 2691.8 2695.9 2696.8 2705.8 2801.4 462969.0 515947.0 516907.0 517163.0 517380.8 65585.7 66500.0 66509.0 66523.7 66608.3 35650.5 36686.4 36703.6 38002.8 68186.3 16691.5 17105.6 17235.4 17299.9 17330.5 10439.5 11832.8 12048.4 13015.8 13108.5 20564.0 20674.7 20714.9 20761.9 20814.9 6822.3 7999.6 8082.8 8116.8 8133.2 727.9 1045.2 1051.1 1053.7 1094.9 29205.6 29243.3 29795.0 30019.7 30100.8 1021.1 1038.2 1065.6 1121.8 1226.8 13159.7 14278.0 15265.5 15274.5 15441.6 691428.0 698763.0 705418.0 705754.0 707451.0 61889.4 62982.4 62988.4 63532.7 63576.7 1301.9 1422.5 1437.3 1448.6 1466.8 30257.2 30441.5 30589.0 30951.0 31065.5 53267.2 53636.2 54076.0 54305.5 54512.0 397492.0 472135.0 478090.0 480107.0 480460.0 23423.3 26323.7 27037.0 27310.6 27976.0 45631.8 46402.3 47102.8 47122.3 47818.5 37242.8 38660.8 38677.8 38681.0 39638.6 32778.5 36911.4 37926.6 38038.8 39069.6 37694.8 38217.0 38235.2 38301.0 38400.8 30711.5 32758.0 32942.0 33056.0 33274.0 24575.4 24763.0 24929.9 25398.0 25460.2 02:01:32 INFO - PROCESS | 1664 | 02:01:32 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:34761.15 Median:2482.08 stddev:103870.73 (4184.8%) stddev-sans-first:104129.92 02:01:32 INFO - PROCESS | 1664 | Values: 319.3 376.4 378.6 389.9 401.4 16438.8 21258.3 21319.1 21370.0 21873.9 19866.4 20499.1 21435.6 21466.0 21490.0 5149.8 5778.6 5968.6 5997.8 6005.1 5784.4 5916.4 5936.4 5962.6 5964.6 5812.3 5872.7 6174.9 6177.1 6186.0 5783.1 6139.0 6174.5 6177.4 6186.8 5930.5 5934.4 5950.0 5978.4 5984.9 13340.9 13434.3 13436.9 13510.4 13561.3 2012.1 2071.5 2111.0 2354.6 2609.6 9223.3 9408.4 10034.7 10143.5 10188.4 1605.7 1666.3 1714.3 1774.8 1814.1 96007.0 106504.0 118085.0 120164.6 122281.0 10350.7 10950.3 11355.5 11587.8 11886.5 14536.5 14619.8 14674.0 14675.8 14685.7 8497.3 8570.8 8588.7 8591.1 8740.0 7001.0 8434.8 8518.2 8523.5 8621.0 4470.8 4480.4 4530.0 4565.8 4594.2 1851.4 1896.8 1897.5 1904.8 1912.5 597.6 710.5 770.8 904.0 953.9 17163.4 19682.4 19845.6 20112.6 20318.2 891.6 909.3 929.4 979.0 1000.3 10458.3 10667.6 10769.4 10829.5 10839.9 618714.0 620017.0 621284.0 630325.0 631239.0 104190.5 131216.0 131246.3 131830.3 133743.3 922.7 975.6 991.7 993.7 1000.4 16198.4 17939.6 17951.9 17970.0 18183.6 36601.0 46576.0 47396.8 47650.3 48406.0 73401.6 74573.3 75409.6 76154.1 76197.0 7806.5 7970.2 7997.7 8161.3 8176.3 7189.3 8755.4 11249.3 12131.0 12472.2 10206.6 10350.7 10557.3 10712.3 10740.5 10380.8 10507.3 10515.9 10671.7 10930.5 10539.0 10788.0 11100.7 11249.2 11284.4 7999.6 8015.2 8032.3 8051.2 8074.9 10248.6 10596.6 10823.7 10847.9 10860.4 02:01:32 INFO - PROCESS | 1664 | 02:01:32 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5638.25 Median:5126.97 stddev:3172.54 (61.9%) stddev-sans-first:3165.09 02:01:32 INFO - PROCESS | 1664 | Values: 1353.6 1558.4 1584.7 1807.3 1956.7 7896.9 7897.6 8686.3 8897.8 8897.9 8627.4 8731.1 8864.7 8874.2 8888.2 1974.8 2136.4 2211.2 2291.7 2406.9 2415.7 2435.7 2445.5 2446.8 2456.7 2358.8 2383.8 2401.1 2401.5 2405.3 2014.7 2094.1 2302.9 2387.6 2390.5 1964.3 2379.5 2410.4 2414.4 2419.2 8713.0 8744.0 8814.6 8822.8 8851.9 5214.6 5283.9 5286.9 5349.0 5365.7 3276.0 3352.7 3467.0 3517.3 3521.1 846.1 894.5 983.7 984.9 1010.2 11607.0 11669.0 11706.7 11707.0 11765.4 4548.5 4654.3 4698.3 4727.6 4731.9 9944.3 9944.7 9972.2 9998.5 10000.2 5232.8 5260.9 5266.2 5266.6 5268.8 4434.3 4593.0 4765.6 4818.4 4915.5 3916.5 3961.6 4027.3 4058.2 4072.9 1261.3 1282.6 1285.7 1296.6 1300.4 787.5 879.4 901.1 935.1 937.1 5608.6 5760.9 5853.2 5899.9 6029.4 909.4 915.3 917.0 939.5 967.7 3823.2 3854.2 3898.0 3920.2 3990.0 10428.7 10599.7 10623.7 10667.6 10765.2 10506.3 10541.6 10729.2 10731.9 10736.7 7770.6 7848.2 8147.3 8208.8 8301.3 5560.2 5665.3 5672.6 5684.0 5726.7 9718.6 9803.2 10043.3 10142.4 10173.6 8768.3 9127.7 9779.0 10200.6 10356.8 6180.9 6363.9 6629.6 6863.5 6917.7 7906.7 7983.2 7984.8 8033.2 8488.5 6697.5 7489.3 7643.4 7647.3 7659.1 7228.8 7289.7 7314.2 7451.3 7454.8 6805.1 6896.0 7041.6 7051.0 7075.1 4748.4 4809.0 4860.5 4949.5 5080.9 4858.7 4918.0 4930.7 4972.0 5173.1 02:01:32 INFO - PROCESS | 1664 | 02:01:32 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:4623.95 Median:5129.85 stddev:1400.72 (27.3%) stddev-sans-first:1366.15 02:01:32 INFO - PROCESS | 1664 | Values: 278.7 292.2 301.8 311.1 311.7 5467.6 5528.6 5532.7 5564.0 5589.3 5011.0 5030.6 5367.7 5452.9 5455.9 3938.9 3945.7 4109.6 4117.6 4155.7 4093.7 4145.7 4155.3 4172.1 4187.0 3469.9 4126.1 4136.0 4143.8 4149.9 3439.0 3647.6 3921.6 4092.7 4138.5 3190.9 3533.7 3549.5 3574.4 3600.0 4323.5 4760.7 5179.8 5207.1 5279.0 4603.6 4718.9 4750.0 4778.7 4803.6 5651.8 5665.0 5718.9 5747.9 5750.5 3995.0 3999.8 4002.5 4020.5 4027.8 5085.8 5646.4 5914.7 5991.6 6009.5 4028.5 4047.6 4058.4 4067.9 4076.9 4145.8 4158.9 4166.4 4170.5 4174.5 4600.9 4787.4 4792.7 4798.9 4816.8 4692.9 4881.6 5744.7 5747.5 5785.0 1410.3 1424.9 1425.3 1429.5 1435.2 819.5 869.8 887.7 888.1 897.2 4553.8 5234.3 5364.6 5367.5 5376.5 5846.1 5887.7 5925.5 5926.7 5940.8 4449.8 5134.7 5392.9 5414.4 5430.3 3052.3 3066.5 3074.8 3079.3 3091.9 4993.5 6142.3 6144.8 6152.9 6178.1 5223.6 6033.3 6063.4 6075.8 6092.4 5203.0 6136.4 6147.4 6150.3 6160.5 4563.4 5200.2 5868.0 5894.9 5936.8 4785.0 4904.1 5124.0 5351.1 5723.6 5140.4 5415.3 5670.3 6163.7 6193.5 3669.5 3970.8 3993.9 4019.5 4035.9 4840.6 5261.6 5698.7 5939.5 5952.2 4698.5 5362.1 5745.8 5800.7 5850.5 4760.8 5124.9 5316.4 5328.5 5764.9 5065.6 5078.7 5175.1 5656.5 5681.9 5028.5 5411.9 5523.3 5577.9 5780.1 5182.9 5436.8 5658.7 5672.7 5708.5 02:01:32 INFO - PROCESS | 1664 | 02:01:32 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:16935.79 Median:30319.43 stddev:22994.16 (75.8%) stddev-sans-first:23026.32 02:01:32 INFO - PROCESS | 1664 | Values: 691.9 694.2 710.9 744.2 755.8 14901.1 15219.0 15407.5 15458.3 15484.5 14262.9 14312.6 14316.6 14324.1 15308.7 892.3 905.2 906.1 906.3 907.5 867.1 869.3 881.8 885.2 901.2 928.2 957.5 958.4 958.9 958.9 947.0 951.2 952.9 953.3 953.9 673.3 696.4 696.6 697.2 697.9 15002.7 15199.4 15224.8 15231.5 15240.7 11869.7 11988.9 12100.5 12352.9 12670.4 10789.4 10861.1 11052.0 11078.4 11516.8 3997.3 4059.2 4091.3 4130.0 4134.1 62247.4 63803.6 77997.0 82589.6 84206.3 9397.2 9463.2 9479.6 9748.7 9783.6 20417.6 20824.8 20862.8 20929.2 21021.0 7409.1 7453.2 7487.0 7508.3 7671.8 3948.6 4014.9 4024.6 4079.7 4306.8 3494.4 3586.0 3586.2 3587.4 3591.6 585.8 593.3 620.7 625.3 632.6 509.9 547.5 575.9 603.5 612.2 7004.6 7338.6 7985.5 8169.6 8250.2 506.6 573.2 586.9 593.2 605.3 3601.9 3626.2 3694.8 3797.7 3920.9 52074.2 71978.6 79072.6 88395.1 95831.5 61381.0 72292.0 73748.0 74789.0 76017.0 52597.5 64067.4 69607.7 72057.6 72758.0 8332.4 8346.0 8362.0 8422.9 8582.1 29187.9 36482.8 43907.1 44820.9 45296.5 59447.1 59869.0 60035.4 60730.7 62486.7 10328.3 10706.0 10914.9 11035.2 11195.7 11769.6 12159.3 12203.2 12298.6 12507.9 11168.5 11280.3 11684.6 11743.9 11772.0 9294.4 10150.4 11517.3 11579.5 11760.8 9944.4 9957.9 11258.3 11555.5 12084.6 9226.7 9270.5 9419.5 9435.5 9456.3 8989.3 9368.3 9541.1 9543.6 9574.2 02:01:32 INFO - PROCESS | 1664 | -------- Summary: end -------- 02:01:32 INFO - PROCESS | 1664 | 02:01:33 INFO - TEST-INFO | 1664: exit 0 02:01:33 INFO - TEST-OK | dromaeo_css | took 1114502ms 02:01:33 INFO - TEST-START | kraken 02:01:33 INFO - operating with platform_type : mac_ 02:01:33 INFO - Initialising browser for kraken test... 02:01:33 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpMZNblV/profile http://localhost:49275/getInfo.html) 02:01:35 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 02:01:35 INFO - PROCESS | 1670 | colorDepth:24 02:01:35 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 02:01:35 INFO - PROCESS | 1670 | __metrics 02:01:35 INFO - PROCESS | 1670 | 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" 02:01:35 ERROR - PROCESS | 1670 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:01:36 INFO - TEST-INFO | 1670: exit 0 02:01:36 INFO - Browser initialized. 02:01:36 INFO - Running cycle 1/1 for kraken test... 02:01:36 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpMZNblV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:01:48 INFO - PROCESS | 1672 | RSS: Main: 239534080 02:01:48 INFO - PROCESS | 1672 | 02:01:50 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 02:01:51 INFO - PROCESS | 1672 | RSS: Main: 287453184 02:01:51 INFO - PROCESS | 1672 | 02:01:54 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 02:01:55 INFO - PROCESS | 1672 | RSS: Main: 296652800 02:01:55 INFO - PROCESS | 1672 | 02:01:58 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 02:01:59 INFO - PROCESS | 1672 | RSS: Main: 385236992 02:01:59 INFO - PROCESS | 1672 | 02:02:01 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 02:02:02 INFO - PROCESS | 1672 | RSS: Main: 284852224 02:02:02 INFO - PROCESS | 1672 | 02:02:04 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:02:05 INFO - PROCESS | 1672 | RSS: Main: 288665600 02:02:05 INFO - PROCESS | 1672 | 02:02:08 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 02:02:08 INFO - PROCESS | 1672 | RSS: Main: 328409088 02:02:08 INFO - PROCESS | 1672 | 02:02:12 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 02:02:12 INFO - PROCESS | 1672 | RSS: Main: 331206656 02:02:12 INFO - PROCESS | 1672 | 02:02:15 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 02:02:16 INFO - PROCESS | 1672 | RSS: Main: 327102464 02:02:16 INFO - PROCESS | 1672 | 02:02:18 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:02:18 INFO - PROCESS | 1672 | RSS: Main: 316760064 02:02:18 INFO - PROCESS | 1672 | 02:02:20 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:02:21 INFO - PROCESS | 1672 | RSS: Main: 323391488 02:02:21 INFO - PROCESS | 1672 | 02:02:23 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:02:24 INFO - PROCESS | 1672 | RSS: Main: 326676480 02:02:24 INFO - PROCESS | 1672 | 02:02:27 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:02:28 INFO - PROCESS | 1672 | RSS: Main: 330993664 02:02:28 INFO - PROCESS | 1672 | 02:02:31 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:02:31 INFO - PROCESS | 1672 | RSS: Main: 328450048 02:02:31 INFO - PROCESS | 1672 | 02:02:33 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 02:02:33 INFO - PROCESS | 1672 | RSS: Main: 375652352 02:02:33 INFO - PROCESS | 1672 | 02:02:33 INFO - PROCESS | 1672 | __start_tp_report 02:02:33 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 02:02:33 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 02:02:33 INFO - PROCESS | 1672 | |i|pagename|runs| 02:02:33 INFO - PROCESS | 1672 | |0;ai-astar;115;108;111;112;114;113;113;112;112;112 02:02:33 INFO - PROCESS | 1672 | |1;audio-beat-detection;159;142;140;178;119;147;160;143;199;117 02:02:33 INFO - PROCESS | 1672 | |2;audio-dft;186;185;186;185;183;183;184;187;182;182 02:02:33 INFO - PROCESS | 1672 | |3;audio-fft;79;57;60;126;81;79;86;57;96;56 02:02:33 INFO - PROCESS | 1672 | |4;audio-oscillator;99;78;165;114;113;76;101;78;78;137 02:02:33 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;91;87;90;88;90;86;91;88;90;87 02:02:33 INFO - PROCESS | 1672 | |6;imaging-darkroom;95;96;97;95;94;96;96;96;96;95 02:02:33 INFO - PROCESS | 1672 | |7;imaging-desaturate;81;86;86;87;108;139;137;83;86;87 02:02:33 INFO - PROCESS | 1672 | |8;json-parse-financial;57;54;56;56;55;57;56;118;51;57 02:02:33 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;65;76;56;75;97;98;100;82;70;69 02:02:33 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;85;85;83;83;85;83;91;106;106;86 02:02:33 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;145;146;135;135;139;119;148;143;249;141 02:02:33 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;170;165;167;166;168;167;167;165;165;167 02:02:33 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;65;66;67;66;65;65;67;65;65;65 02:02:33 INFO - PROCESS | 1672 | __end_tp_report 02:02:33 INFO - PROCESS | 1672 | __start_cc_report 02:02:33 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,2255 02:02:33 INFO - PROCESS | 1672 | __end_cc_report 02:02:33 INFO - PROCESS | 1672 | __startTimestamp1464080553689__endTimestamp 02:02:33 INFO - PROCESS | 1672 | 02:02:33 INFO - PROCESS | 1672 | ------- Summary: start ------- 02:02:33 INFO - PROCESS | 1672 | Number of tests: 14 02:02:33 INFO - PROCESS | 1672 | 02:02:33 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:112.20 Median:112.50 stddev:1.87 (1.7%) stddev-sans-first:1.69 02:02:33 INFO - PROCESS | 1672 | Values: 115.0 108.0 111.0 112.0 114.0 113.0 113.0 112.0 112.0 112.0 02:02:33 INFO - PROCESS | 1672 | 02:02:33 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:150.40 Median:153.00 stddev:25.03 (16.4%) stddev-sans-first:26.35 02:02:33 INFO - PROCESS | 1672 | Values: 159.0 142.0 140.0 178.0 119.0 147.0 160.0 143.0 199.0 117.0 02:02:33 INFO - PROCESS | 1672 | 02:02:33 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:184.30 Median:185.00 stddev:1.77 (1.0%) stddev-sans-first:1.76 02:02:33 INFO - PROCESS | 1672 | Values: 186.0 185.0 186.0 185.0 183.0 183.0 184.0 187.0 182.0 182.0 02:02:33 INFO - PROCESS | 1672 | 02:02:33 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:77.70 Median:79.00 stddev:22.09 (28.0%) stddev-sans-first:23.43 02:02:33 INFO - PROCESS | 1672 | Values: 79.0 57.0 60.0 126.0 81.0 79.0 86.0 57.0 96.0 56.0 02:02:33 INFO - PROCESS | 1672 | 02:02:33 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:103.90 Median:77.00 stddev:29.51 (38.3%) stddev-sans-first:31.25 02:02:33 INFO - PROCESS | 1672 | Values: 99.0 78.0 165.0 114.0 113.0 76.0 101.0 78.0 78.0 137.0 02:02:33 INFO - PROCESS | 1672 | 02:02:33 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:88.80 Median:90.00 stddev:1.81 (2.0%) stddev-sans-first:1.74 02:02:33 INFO - PROCESS | 1672 | Values: 91.0 87.0 90.0 88.0 90.0 86.0 91.0 88.0 90.0 87.0 02:02:33 INFO - PROCESS | 1672 | 02:02:33 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:95.60 Median:96.00 stddev:0.84 (0.9%) stddev-sans-first:0.87 02:02:33 INFO - PROCESS | 1672 | Values: 95.0 96.0 97.0 95.0 94.0 96.0 96.0 96.0 96.0 95.0 02:02:33 INFO - PROCESS | 1672 | 02:02:33 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:98.00 Median:86.00 stddev:22.34 (26.0%) stddev-sans-first:22.83 02:02:33 INFO - PROCESS | 1672 | Values: 81.0 86.0 86.0 87.0 108.0 139.0 137.0 83.0 86.0 87.0 02:02:33 INFO - PROCESS | 1672 | 02:02:33 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:61.70 Median:56.00 stddev:19.87 (35.5%) stddev-sans-first:21.00 02:02:33 INFO - PROCESS | 1672 | Values: 57.0 54.0 56.0 56.0 55.0 57.0 56.0 118.0 51.0 57.0 02:02:33 INFO - PROCESS | 1672 | 02:02:33 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:78.80 Median:75.50 stddev:15.15 (20.1%) stddev-sans-first:15.22 02:02:33 INFO - PROCESS | 1672 | Values: 65.0 76.0 56.0 75.0 97.0 98.0 100.0 82.0 70.0 69.0 02:02:33 INFO - PROCESS | 1672 | 02:02:33 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:89.30 Median:85.00 stddev:9.10 (10.7%) stddev-sans-first:9.52 02:02:33 INFO - PROCESS | 1672 | Values: 85.0 85.0 83.0 83.0 85.0 83.0 91.0 106.0 106.0 86.0 02:02:33 INFO - PROCESS | 1672 | 02:02:33 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:150.00 Median:144.00 stddev:35.76 (24.8%) stddev-sans-first:37.88 02:02:33 INFO - PROCESS | 1672 | Values: 145.0 146.0 135.0 135.0 139.0 119.0 148.0 143.0 249.0 141.0 02:02:33 INFO - PROCESS | 1672 | 02:02:33 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.70 Median:167.00 stddev:1.57 (0.9%) stddev-sans-first:1.12 02:02:33 INFO - PROCESS | 1672 | Values: 170.0 165.0 167.0 166.0 168.0 167.0 167.0 165.0 165.0 167.0 02:02:33 INFO - PROCESS | 1672 | 02:02:33 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:65.50 stddev:0.84 (1.3%) stddev-sans-first:0.87 02:02:33 INFO - PROCESS | 1672 | Values: 65.0 66.0 67.0 66.0 65.0 65.0 67.0 65.0 65.0 65.0 02:02:33 INFO - PROCESS | 1672 | -------- Summary: end -------- 02:02:33 INFO - PROCESS | 1672 | 02:02:34 INFO - TEST-INFO | 1672: exit 0 02:02:34 INFO - TEST-OK | kraken | took 61398ms 02:02:34 INFO - SUITE-END | took 1175s 02:02:34 INFO - Completed test suite (00:19:36) 02:02:34 INFO - javascript benchmark 02:02:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3465.4108801503726, 3474.5135681004654, 3486.7036301914336, 3501.45727412251, 3531.0642679689886, 4696.483386827446, 5649.4068122847075, 5654.519365853897, 5759.510441612463, 5780.364159942403, 4721.9055618887605, 4845.709257444556, 5681.431856814316, 5733.971330143354, 5800.941990580103, 4614.192516309641, 5212.895742085156, 5231.032259032072, 5307.787688492455, 5313.203019547061, 5266.52601265886, 5326.493983071621, 5388.91916621251, 5429.6742195468205, 5461.0716178249595, 5375.193720941851, 5385.515303622654, 5416.268803711508, 5417.674939503623, 5457.536109430703, 5190.4031036430915, 5250.949810038008, 5263.105272103751, 5267.104592219293, 5371.623986320959, 5226.137687281594, 5278.630495865291, 5280.2079688046715, 5291.3385826771655, 5312.389075256357, 5644.379118296984, 5693.629914055573, 5747.655140691572, 5763.991301522216, 5777.682227477487, 6087.190849751468, 6170.136180934673, 6189.9381006189815, 6253.781117660906, 6262.749490020394, 6262.71817768197, 6292.244930608311, 6349.0, 6363.618182908994, 6379.904301435482, 5855.151003104568, 5856.824295271149, 5859.062549991981, 5873.500752436048, 5924.792632257893, 6265.498760099181, 6280.623162610258, 6342.175517182736, 6345.841353966188, 6357.300696923335, 6242.282137554149, 6253.78111766086, 6256.593321434092, 6265.310815810257, 6271.090691849657, 6180.6909654517995, 6289.0, 6336.936630633727, 6341.207349081365, 6359.332270111664, 6185.443310102022, 6278.905816412757, 6286.779962701375, 6345.873082538324, 6349.111124442582, 6272.153259309935, 6354.142190804275, 6354.618722876642, 6364.172657554581, 6367.108604795332, 4862.95137048632, 4896.2410826321975, 4987.850364489072, 5011.924821127686, 5060.291559181717, 4424.137293227854, 4427.026054268056, 4435.223835828717, 4456.888577785581, 4479.865604031885, 4726.645501587326, 5809.2738407699035, 5811.157382179645, 5876.324222714443, 5883.823485295448, 6270.184875966095, 6275.560710750204, 6310.148130002391, 6321.399467050623, 6322.241331040304, 5652.63257888236, 5773.538116950718, 5811.535077193815, 5834.533237341003, 5851.151583020438, 5427.1045277528765, 5434.510894019557, 5483.040467918098, 5494.80768173112, 5524.06090964537, 6419.605499103389, 6453.128827608213, 6475.255345635313, 6481.578697384655, 6482.643454610042, 6221.9377806221355, 6295.716692748746, 6296.212973378328, 6334.398232167937, 6360.618362898056, 5356.0, 6305.747770089145, 6368.395002474757, 6409.615423074631, 6457.354264573693, 5209.4009188943755, 6273.162253144094, 6292.433680968735, 6302.086197501429, 6323.747050117944, 5097.541221290102, 6224.346443623354, 6314.715837787402, 6349.301576826638, 6351.587146835441, 4732.290156476556, 5540.919520693426, 6140.754369825157, 6200.410960958701, 6202.162708034538, 6054.939450605438, 6188.0717892314715, 6194.690265486617, 6199.473044791243, 6204.255489341308, 5903.970480147572, 6069.666168360783, 6078.574499784972, 6088.665123418255, 6123.050927106306, 6160.0, 6200.844978875564, 6213.658248796179, 6230.563860529719, 6231.688415579293, 6118.908216376669, 6127.540434467343, 6138.969305153446, 6159.692015399122, 6170.259568851767], "value": 5731.855911628848, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [475.94526629437627, 483.4005832767367, 484.5614718679594, 487.71712406804056, 507.13786562843177, 31716.68283317166, 42516.0, 43719.78140109303, 43721.34417983725, 43771.90570235738, 45235.86910327235, 45803.54196458034, 45882.770586147104, 45972.540274597246, 46302.305465418045, 8443.577821108951, 9447.0, 9614.711558653216, 9664.661736839213, 9696.0, 8631.050584435707, 11513.136514761427, 11672.649820505398, 11755.647330580054, 11999.280043197392, 11861.347625880573, 11916.642500724949, 11922.986546143587, 11965.282083075, 11973.341466219354, 8539.658413663446, 11528.827067593993, 11576.24754390962, 11676.649700508955, 11775.470103845348, 11746.236494627907, 11839.881601183964, 11850.525978960832, 11856.347900865449, 11865.52537898483, 40815.387769183486, 41539.7923010386, 41826.0, 41884.0, 41941.580584194075, 2946.381259935416, 3105.5652208690803, 3353.144948038259, 3438.7420943429343, 3732.5520937487677, 28835.85582072076, 29000.564991525145, 29522.852385738148, 29572.55641165384, 29702.702972970208, 2691.7981151413724, 2695.9460810783676, 2696.8381897086238, 2705.7700095491905, 2801.3836955869674, 462969.0, 515947.0, 516907.0, 517162.9999999962, 517380.82619173324, 65585.67207163933, 66500.0, 66509.0, 66523.66738166279, 66608.33391666021, 35650.465243021375, 36686.449703254475, 36703.632963670025, 38002.80998594989, 68186.318136818, 16691.49925502237, 17105.572360691083, 17235.3967611133, 17299.913500432416, 17330.480085597454, 10439.530221139916, 11832.763344733057, 12048.397580120958, 13015.804762928563, 13108.475660973645, 20564.0, 20674.689879651818, 20714.896425517774, 20761.89619051895, 20814.895925520577, 6822.283660215705, 7999.5600241986085, 8082.757517274491, 8116.837663246702, 8133.170036741686, 727.8980942667924, 1045.1951996962296, 1051.1433181956834, 1053.7049626104704, 1094.8795632480421, 29205.561916571267, 29243.268918276786, 29794.95717649872, 30019.69980300169, 30100.84949575282, 1021.1116328793996, 1038.2317085356933, 1065.6003998500562, 1121.7980763462492, 1226.8098444741079, 13159.67100822487, 14278.0, 15265.542033739006, 15274.465393711389, 15441.613959650875, 691428.0, 698763.0, 705418.0, 705754.0, 707451.0, 61889.38110618926, 62982.37017629765, 62988.370116299164, 63532.68233658894, 63576.68211658912, 1301.8502872169631, 1422.4879043544522, 1437.3100911562299, 1448.608875603581, 1466.8459811720043, 30257.24356891095, 30441.543376848924, 30589.0, 30951.0, 31065.534016990216, 53267.20099198438, 53636.195457068956, 54076.0, 54305.45694543162, 54512.0, 397492.0, 472135.0, 478090.0, 480107.0, 480460.0, 23423.297301080995, 26323.73676263213, 27037.0, 27310.5903411445, 27976.0, 45631.77184114058, 46402.30396543987, 47102.76448617735, 47122.293165601855, 47818.5218147828, 37242.8137859309, 38660.80669596634, 38677.80661096789, 38681.03297417587, 39638.60361396349, 32778.50832237566, 36911.41620086568, 37926.62073379231, 38038.80980595079, 39069.60930390773, 37694.8115259422, 38217.0, 38235.23529529449, 38301.0, 38400.80799595984, 30711.539326910563, 32758.0, 32942.011739647845, 33056.0, 33274.0, 24575.38561535976, 24763.009479620614, 24929.87535062313, 25398.0, 25460.236192914243], "value": 20204.774609812048, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.31188289236695, 376.3677022602029, 378.6459660217695, 389.9434581985614, 401.35382034923765, 16438.75341869873, 21258.255961041366, 21319.147234110656, 21370.038348274327, 21873.890630546844, 19866.40400787975, 20499.07754151063, 21435.571288574218, 21466.0, 21490.0, 5149.76826042829, 5778.6243894146955, 5968.641881487125, 5997.760089596411, 6005.069214271794, 5784.421557844203, 5916.3787802280785, 5936.376680448556, 5962.552808539355, 5964.582479226456, 5812.273465816773, 5872.677002764846, 6174.876502469948, 6177.104319873616, 6186.041163619646, 5783.103618939072, 6139.0, 6174.536909731788, 6177.3822617738315, 6186.84532886677, 5930.525557955353, 5934.397712617569, 5950.047992321208, 5978.40215978403, 5984.9401505984815, 13340.86659133406, 13434.261115638636, 13436.932815335958, 13510.392032358568, 13561.25413102279, 2012.1347820437206, 2071.5028393185676, 2111.0289266937075, 2354.564405584972, 2609.595512695535, 9223.262139028862, 9408.43549387039, 10034.698959031168, 10143.543540540693, 10188.388696678148, 1605.710972025035, 1666.2834980958182, 1714.271434640273, 1774.8225177482148, 1814.0748218408576, 96007.0, 106504.0, 118085.0, 120164.59670806622, 122281.0, 10350.689479315633, 10950.34297942126, 11355.54577816878, 11587.826182607268, 11886.465109070112, 14536.491222807152, 14619.78070328946, 14674.0, 14675.77986330206, 14685.706285874436, 8497.320214382835, 8570.828583428422, 8588.656453741905, 8591.140885911484, 8739.951205855337, 7000.964995174992, 8434.789130271793, 8518.23335899772, 8523.48859068458, 8621.00858401292, 4470.821167153343, 4480.439945006874, 4529.9547004530195, 4565.768210202872, 4594.1730488512385, 1851.4445666300055, 1896.8103189680921, 1897.5066482714594, 1904.8190421909896, 1912.4836294200766, 597.6264834478451, 710.4884483172108, 770.7995921060565, 903.9909600903907, 953.8855337359561, 17163.40240325492, 19682.409527714193, 19845.60308793816, 20112.59774804496, 20318.18727250923, 891.6032365597247, 909.2771246858763, 929.3773171974658, 978.9755256118511, 1000.2548101664306, 10458.320209186379, 10667.626633067805, 10769.407682577377, 10829.458527073772, 10839.945800271105, 618714.0, 620017.0, 621284.0, 630325.0, 631239.0, 104190.4790476058, 131216.0, 131246.34376828055, 131830.34084829706, 133743.33128334297, 922.7462447826902, 975.5853762151199, 991.7058238998177, 993.7267251505893, 1000.3797645459862, 16198.433054843024, 17939.641207176046, 17951.910240449237, 17970.0, 18183.636327273645, 36601.0, 46576.0, 47396.7630161847, 47650.285245720646, 48406.0, 73401.6329918347, 74573.25426745664, 75409.62295188708, 76154.07174704972, 76197.0, 7806.477736841489, 7970.1631328709655, 7997.729162827961, 8161.265486106042, 8176.345892328718, 7189.2810718927685, 8755.387122901591, 11249.25636526307, 12131.0, 12472.189307694971, 10206.59173633076, 10350.689479315633, 10557.260991730505, 10712.250142489951, 10740.462976851282, 10380.844287335545, 10507.264491485523, 10515.894841051797, 10671.733206669895, 10930.45347732626, 10539.0, 10788.0, 11100.722481938015, 11249.1563132767, 11284.379359135326, 7999.600019999093, 8015.238552337519, 8032.277095061472, 8051.1546287641295, 8074.919250807652, 10248.590056397661, 10596.576136954744, 10823.67528974132, 10847.945760271148, 10860.402677852793], "value": 9160.834475774436, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1353.6480515066091, 1558.4467514032517, 1584.6751415959725, 1807.2861219818171, 1956.696712009638, 7896.92103078969, 7897.605119744011, 8686.348523860712, 8897.777555561113, 8897.911020889796, 8627.439216450943, 8731.083236260196, 8864.73405797827, 8874.20132188103, 8888.155625215593, 1974.7926467720897, 2136.39112852837, 2211.1818627107914, 2291.6906217660653, 2406.9398265043337, 2415.7463466336044, 2435.671184390111, 2445.461998360358, 2446.779789818916, 2456.742042085582, 2358.7641235876445, 2383.7854593086618, 2401.0755858994326, 2401.5317013182434, 2405.326508577601, 2014.7307196466206, 2094.1204694028543, 2302.861828290308, 2387.6060450025725, 2390.4501964548076, 1964.3223088034606, 2379.5240951809533, 2410.3612542676206, 2414.38433199533, 2419.1774796569252, 8713.0, 8744.0, 8814.603342849587, 8822.779430514225, 8851.867221991675, 5214.556762675177, 5283.894322113575, 5286.867828304285, 5349.037173308803, 5365.704886231256, 3275.983620081908, 3352.715019223369, 3467.0465621954163, 3517.278957813642, 3521.1197200699826, 846.0735494633428, 894.4767311123001, 983.6704703924194, 984.9359791613522, 1010.2019404670301, 11607.0, 11669.00813430851, 11706.707332316675, 11707.0, 11765.411729413581, 4548.476925153584, 4654.25531914892, 4698.295255711602, 4727.57451829337, 4731.881702957454, 9944.25418093646, 9944.701658950244, 9972.202223822076, 9998.450085245382, 10000.249981251436, 5232.790688372423, 5260.92108618371, 5266.183741520071, 5266.552343050884, 5268.762905669254, 4434.33484977256, 4592.977035114803, 4765.571098601074, 4818.4217893852965, 4915.533024362751, 3916.5104361954754, 3961.564227934925, 4027.335489644176, 4058.1680755445063, 4072.8778136655355, 1261.3377976562194, 1282.58316047285, 1285.6785803549112, 1296.623979046066, 1300.4408104515148, 787.4920676163957, 879.4064006795516, 901.0538934119148, 935.050923312838, 937.0722984245684, 5608.635438696471, 5760.942390576124, 5853.151293062482, 5899.882002359929, 6029.427204415573, 909.4452384045727, 915.3135148638521, 916.9541522923827, 939.5490164720973, 967.7435479597913, 3823.1971286030243, 3854.1520865409566, 3897.9610203898164, 3920.17676287977, 3989.960100399017, 10428.687139385831, 10599.735006624896, 10623.681289561171, 10667.626633067805, 10765.24643274979, 10506.264561480622, 10541.57833686644, 10729.248952573402, 10731.946340268249, 10736.677899663022, 7770.611469426506, 7848.215178482106, 8147.307478864363, 8208.794780130425, 8301.335893128535, 5560.221568980265, 5665.3201615806365, 5672.602917795714, 5684.0, 5726.685032323096, 9718.611255549698, 9803.16673082796, 10043.347182433119, 10142.391456512632, 10173.593056277667, 8768.34237432208, 9127.726168214964, 9778.951105244712, 10200.642977495752, 10356.792864142524, 6180.876382472416, 6363.87272254543, 6629.6353700545005, 6863.450923926173, 6917.688704008231, 7906.683732650629, 7983.2415920487465, 7984.760457186294, 8033.236842499953, 8488.490690558587, 6697.531172817856, 7489.325960663348, 7643.350315223268, 7647.311741943252, 7659.1192012917, 7228.783136505913, 7289.744858929912, 7314.232005639331, 7451.3293803557945, 7454.776356709307, 6805.149356330458, 6896.000079988474, 7041.647917603997, 7050.964745176241, 7075.080239568823, 4748.430188377417, 4808.9519104808505, 4860.538248866352, 4949.505049495022, 5080.872978175576, 4858.684185527929, 4917.975410123069, 4930.6795058321095, 4972.005598880166, 5173.0688476074665], "value": 4523.096305634758, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [278.6725597423029, 292.1513004721285, 301.8309746541936, 311.117980525211, 311.7365825877133, 5467.5899307551945, 5528.55771538277, 5532.727472681286, 5564.0, 5589.329280486348, 5011.047900898827, 5030.560178313515, 5367.731613419323, 5452.945470545294, 5455.890882182364, 3938.8621398251066, 3945.7040721945755, 4109.568495307994, 4117.649999750025, 4155.667546596265, 4093.713440059197, 4145.689073319498, 4155.252054630166, 4172.08214192877, 4186.97419132313, 3469.8959031229106, 4126.071633882368, 4135.966008497876, 4143.7513749175, 4149.8962525936795, 3438.965610343902, 3647.6352364763575, 3921.588233235512, 4092.6930480214105, 4138.503379594438, 3190.9469874941215, 3533.717302615785, 3549.503069570262, 3574.4459608760685, 3599.982000090009, 4323.546027667081, 4760.738159401231, 5179.808644011899, 5207.062728708831, 5278.97360513195, 4603.562661547147, 4718.882027949295, 4749.978754567785, 4778.713277203379, 4803.567678908915, 5651.784866253775, 5664.971675141598, 5718.942810571883, 5747.913781293284, 5750.45370689788, 3995.003747189608, 3999.8000099995174, 4002.459667944819, 4020.457238272825, 4027.7784721840285, 5085.7622646036, 5646.402654778807, 5914.669199430094, 5991.580589358747, 6009.549283803689, 4028.4561584186345, 4047.615476529725, 4058.370952502367, 4067.9389809152885, 4076.9388459173138, 4145.79271036447, 4158.9376159357635, 4166.437530933285, 4170.4995400552125, 4174.457320548309, 4600.930986035212, 4787.40157480315, 4792.7124372537755, 4798.880027999328, 4816.783244753994, 4692.882677933079, 4881.609471242292, 5744.655320680772, 5747.540196784247, 5785.0, 1410.344189951657, 1424.921629310377, 1425.258865390012, 1429.4568064135563, 1435.2321507993381, 819.4591569564059, 869.8216865542548, 887.7026196224208, 888.1163242573551, 897.2373482539789, 4553.814169198803, 5234.26720259164, 5364.597655175799, 5367.543758780469, 5376.542993845567, 5846.070796637099, 5887.676177810262, 5925.525957923442, 5926.733297001645, 5940.762369505258, 4449.7997590108525, 5134.743262836843, 5392.892142157135, 5414.40441551429, 5430.321209848769, 3052.290717198376, 3066.5400189971683, 3074.8155110692983, 3079.276370053036, 3091.9332830173553, 4993.525615066563, 6142.293636231801, 6144.754209831557, 6152.938470615326, 6178.104174894705, 5223.608229382811, 6033.306169790442, 6063.393660633989, 6075.787347442818, 6092.421219984094, 5203.0, 6136.422702464975, 6147.354527774609, 6150.292716337678, 6160.507159427235, 4563.3839431677625, 5200.191896376066, 5867.970660146672, 5894.911576326273, 5936.821895343147, 4784.976075119602, 4904.068227036852, 5124.0, 5351.090314646442, 5723.59771855898, 5140.3831540215415, 5415.296011518478, 5670.262865827416, 6163.691815409122, 6193.504519638328, 3669.522962014921, 3970.841166353429, 3993.940090898697, 4019.4878201124297, 4035.91928161441, 4840.588549973291, 5261.605379596469, 5698.686572238566, 5939.46544810969, 5952.196453478725, 4698.530146985274, 5362.115250983544, 5745.770169193353, 5800.6809625471005, 5850.5027072699295, 4760.78576464053, 5124.949385375914, 5316.415194328698, 5328.536451306527, 5764.942350576608, 5065.594752419871, 5078.7206703631655, 5175.068487672255, 5656.490915817596, 5681.9431805681415, 5028.472010438852, 5411.891762164656, 5523.28197334344, 5577.9442205577425, 5780.132980053026, 5182.870428239325, 5436.809711660073, 5658.688772117573, 5672.688002159921, 5708.457696518824], "value": 4173.288937791098, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [691.927347628499, 694.163532942804, 710.8613820305043, 744.1925510820756, 755.7506023012406, 14901.105933643976, 15219.0, 15407.537773866788, 15458.304376303066, 15484.458043968463, 14262.928685356559, 14312.642183945407, 14316.570502884904, 14324.140551566914, 15308.6938261235, 892.321835405092, 905.2260317428603, 906.084854297158, 906.2523418179995, 907.4645958884257, 867.119873328572, 869.2958703450194, 881.8412685716569, 885.2033170146855, 901.197933838884, 928.16465181337, 957.5355952363133, 958.3674774648733, 958.8513780364054, 958.9136977672008, 946.9810603787954, 951.1772316945861, 952.9237660987139, 953.2850362228329, 953.8712273843012, 673.2829536543566, 696.4045740891515, 696.5681277607915, 697.1842943755818, 697.9127609048869, 15002.69994600102, 15199.392024319013, 15224.771628425471, 15231.543053708407, 15240.695186096216, 11869.703257418547, 11988.940055299669, 12100.455479503444, 12352.949999250075, 12670.429830657551, 10789.352638841694, 10861.1311095113, 11052.0, 11078.390688512129, 11516.769664606745, 3997.320455522568, 4059.2084543514025, 4091.345384738457, 4130.029443080904, 4134.131832315187, 62247.37752622461, 63803.60798933162, 77997.0, 82589.58705206496, 84206.31587368279, 9397.201237894855, 9463.195628371666, 9479.620815167316, 9748.658796942073, 9783.608655653836, 20417.59164816725, 20824.791752082587, 20862.791372086387, 20929.162833486793, 21021.0, 7409.110906691231, 7453.217412171753, 7486.962565187139, 7508.2867127622785, 7671.808204794813, 3948.58539853317, 4014.9197016059516, 4024.597540245952, 4079.7144199906315, 4306.82772689089, 3494.353544594258, 3586.049696830342, 3586.228960773446, 3587.408077667208, 3591.586967498771, 585.844751140948, 593.2880543348, 620.6989610039127, 625.3308959413384, 632.6393955445352, 509.943906170321, 547.5400663442648, 575.9164921086419, 603.4629180029777, 612.1582823617526, 7004.614746189009, 7338.596377199198, 7985.520868747778, 8169.591520423955, 8250.216229458192, 506.6073792810601, 573.1746285349084, 586.8503531599415, 593.2199158107076, 605.2555356911024, 3601.8758521492746, 3626.220362622049, 3694.8152592370275, 3797.734158608926, 3920.9019774505864, 52074.21888671673, 71978.56042879113, 79072.6046369776, 88395.11604883998, 95831.52084239674, 61381.0, 72292.0, 73748.0, 74789.0, 76017.0, 52597.474025260024, 64067.35932640707, 69607.65196173987, 72057.63971180216, 72758.0, 8332.416730828783, 8346.01433909925, 8362.0, 8422.915770842214, 8582.0988796178, 29187.85406072956, 36482.8175859119, 43907.12185756331, 44820.87947801331, 45296.547034529234, 59447.10829337477, 59869.0, 60035.39964600298, 60730.696346519755, 62486.68756656188, 10328.328658637165, 10706.0, 10914.945425272823, 11035.174681381852, 11195.664130076111, 11769.646910592695, 12159.331236782062, 12203.20679155852, 12298.569550065704, 12507.876965520009, 11168.497417616065, 11280.26678265943, 11684.591039313584, 11743.88256117428, 11772.0, 9294.442333460014, 10150.390976541432, 11517.308961462339, 11579.536818527165, 11760.76478470443, 9944.403335799876, 9957.900420995697, 11258.268212566158, 11555.537778488766, 12084.577039803564, 9226.723198304062, 9270.490123043297, 9419.481928493724, 9435.481048542397, 9456.2907781918, 8989.280857531512, 9368.278499163633, 9541.093596108358, 9543.570539325887, 9574.234061275221], "value": 6757.729423955755, "unit": "score"}], "name": "dromaeo_css", "value": 7165.40580295582, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 108.0, 111.0, 112.0, 114.0, 113.0, 113.0, 112.0, 112.0, 112.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 142.0, 140.0, 178.0, 119.0, 147.0, 160.0, 143.0, 199.0, 117.0], "value": 150.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 186.0, 185.0, 183.0, 183.0, 184.0, 187.0, 182.0, 182.0], "value": 184.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 57.0, 60.0, 126.0, 81.0, 79.0, 86.0, 57.0, 96.0, 56.0], "value": 77.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 78.0, 165.0, 114.0, 113.0, 76.0, 101.0, 78.0, 78.0, 137.0], "value": 103.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 90.0, 88.0, 90.0, 86.0, 91.0, 88.0, 90.0, 87.0], "value": 88.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 97.0, 95.0, 94.0, 96.0, 96.0, 96.0, 96.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 86.0, 86.0, 87.0, 108.0, 139.0, 137.0, 83.0, 86.0, 87.0], "value": 98.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 54.0, 56.0, 56.0, 55.0, 57.0, 56.0, 118.0, 51.0, 57.0], "value": 61.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 76.0, 56.0, 75.0, 97.0, 98.0, 100.0, 82.0, 70.0, 69.0], "value": 78.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 83.0, 83.0, 85.0, 83.0, 91.0, 106.0, 106.0, 86.0], "value": 89.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 135.0, 135.0, 139.0, 119.0, 148.0, 143.0, 249.0, 141.0], "value": 150.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 165.0, 167.0, 166.0, 168.0, 167.0, 167.0, 165.0, 165.0, 167.0], "value": 166.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 67.0, 66.0, 65.0, 65.0, 67.0, 65.0, 65.0, 65.0], "value": 65.6, "unit": "score"}], "name": "kraken", "value": 1523.0, "alertThreshold": 2.0}]} 02:02:34 INFO - Return code: 0 02:02:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:02:34 ERROR - # TBPL SUCCESS # 02:02:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:02:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:02:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:02:35 INFO - 2016-05-24 02:02:34.999 screenresolution[1676:10259] starting screenresolution argv=screenresolution get 02:02:35 INFO - 2016-05-24 02:02:35.001 screenresolution[1676:10259] Display 0: 1600x1200x32@60 02:02:35 INFO - 2016-05-24 02:02:35.007 screenresolution[1677:10262] starting screenresolution argv=screenresolution list 02:02:35 INFO - Available Modes on Display 0 02:02:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:02:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:02:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:02:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:02:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:02:35 INFO - Intel Iris: 02:02:35 INFO - Chipset Model: Intel Iris 02:02:35 INFO - Type: GPU 02:02:35 INFO - Bus: Built-In 02:02:35 INFO - VRAM (Dynamic, Max): 1536 MB 02:02:35 INFO - Vendor: Intel (0x8086) 02:02:35 INFO - Device ID: 0x0a2e 02:02:35 INFO - Revision ID: 0x0009 02:02:35 INFO - Displays: 02:02:35 INFO - Display: 02:02:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:02:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:02:35 INFO - Display Serial Number: 1600x1200 60 02:02:35 INFO - Main Display: Yes 02:02:35 INFO - Mirror: Off 02:02:35 INFO - Online: Yes 02:02:35 INFO - Rotation: Supported 02:02:35 INFO - Return code: 0 02:02:35 INFO - Running post-action listener: _resource_record_post_action 02:02:35 INFO - ##### 02:02:35 INFO - ##### Finished run-tests step (success) 02:02:35 INFO - ##### 02:02:35 INFO - Running post-run listener: _resource_record_post_run 02:02:35 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 32652800; Write bytes: 695589376; Read time: 324; Write time: 1909 02:02:35 INFO - TinderboxPrint: CPU usage
53.8% 02:02:35 INFO - TinderboxPrint: I/O read bytes / time
32,652,800 / 324 02:02:35 INFO - TinderboxPrint: I/O write bytes / time
695,589,376 / 1,909 02:02:35 INFO - TinderboxPrint: CPU idle
2,241.5 (46.2%) 02:02:35 INFO - TinderboxPrint: CPU system
973.2 (20.1%) 02:02:35 INFO - TinderboxPrint: CPU user
1,634.9 (33.7%) 02:02:35 INFO - TinderboxPrint: Swap in / out
424,865,792 / 0 02:02:35 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 397294080; Write bytes: 398923264; Read time: 29833; Write time: 771 02:02:35 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 30871040; Write bytes: 295818240; Read time: 306; Write time: 1136 02:02:35 INFO - Running post-run listener: _upload_blobber_files 02:02:35 INFO - Blob upload gear active. 02:02:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:02: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 02:02: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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:02: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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:02:36 INFO - (blobuploader) - INFO - Open directory for files ... 02:02:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:02:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:02:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:02:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:02:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:02:37 INFO - (blobuploader) - INFO - Done attempting. 02:02:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:02:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:02:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:02:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:02:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:02:38 INFO - (blobuploader) - INFO - Done attempting. 02:02:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:02:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:02:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:02:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:02:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:02:40 INFO - (blobuploader) - INFO - Done attempting. 02:02:40 INFO - (blobuploader) - INFO - Iteration through files over. 02:02:40 INFO - Return code: 0 02:02:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:02:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:02:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4dbbecaf550bca388f1c397e51753fc297ebe6642e5b91b4378f7e1f9e36cf04eb6f77347d6f48adb19a094ce803d8ffc9ad6adea54525288afe6ab78c2a2c1b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32c57e6636b594f3d0a250c8bbb178eefca7047529440f1bf494524b00d9b772392cd17d298804fd82ba4f8d57426838d92dfd90686b2cb04ca51cf8a2428d28", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8d873506f1b9ea3c37c3bda86946e5e19af830de085da9a20f63cc76e7b3864fe9a7ab2960373772d8bfdff48abbe7271bf108682d793c5600fa120d6092296"} 02:02:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:02:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:02:40 INFO - Contents: 02:02:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4dbbecaf550bca388f1c397e51753fc297ebe6642e5b91b4378f7e1f9e36cf04eb6f77347d6f48adb19a094ce803d8ffc9ad6adea54525288afe6ab78c2a2c1b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32c57e6636b594f3d0a250c8bbb178eefca7047529440f1bf494524b00d9b772392cd17d298804fd82ba4f8d57426838d92dfd90686b2cb04ca51cf8a2428d28", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8d873506f1b9ea3c37c3bda86946e5e19af830de085da9a20f63cc76e7b3864fe9a7ab2960373772d8bfdff48abbe7271bf108682d793c5600fa120d6092296"} 02:02:40 INFO - Running post-run listener: copy_logs_to_upload_dir 02:02:40 INFO - Copying logs to upload dir... 02:02:40 INFO - mkdir: /builds/slave/test/build/upload/logs 02:02:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.717127 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-05-24 02:02:40.606231) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 02:02:40.609667) ========= 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.BZNpbYMdUk/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.aSY25GHG9W/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4dbbecaf550bca388f1c397e51753fc297ebe6642e5b91b4378f7e1f9e36cf04eb6f77347d6f48adb19a094ce803d8ffc9ad6adea54525288afe6ab78c2a2c1b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32c57e6636b594f3d0a250c8bbb178eefca7047529440f1bf494524b00d9b772392cd17d298804fd82ba4f8d57426838d92dfd90686b2cb04ca51cf8a2428d28", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8d873506f1b9ea3c37c3bda86946e5e19af830de085da9a20f63cc76e7b3864fe9a7ab2960373772d8bfdff48abbe7271bf108682d793c5600fa120d6092296"} build_url:https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011095 build_url: 'https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4dbbecaf550bca388f1c397e51753fc297ebe6642e5b91b4378f7e1f9e36cf04eb6f77347d6f48adb19a094ce803d8ffc9ad6adea54525288afe6ab78c2a2c1b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32c57e6636b594f3d0a250c8bbb178eefca7047529440f1bf494524b00d9b772392cd17d298804fd82ba4f8d57426838d92dfd90686b2cb04ca51cf8a2428d28", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8d873506f1b9ea3c37c3bda86946e5e19af830de085da9a20f63cc76e7b3864fe9a7ab2960373772d8bfdff48abbe7271bf108682d793c5600fa120d6092296"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 02:02:40.638886) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 02:02:40.639220) ========= 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.BZNpbYMdUk/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.aSY25GHG9W/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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.005103 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 02:02:40.686851) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 02:02:40.687157) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 02:02:40.687473) ========= ========= Total master_lag: 0.26 =========