BIND 10 #3217: multiple start up problems

BIND 10 Development do-not-reply at isc.org
Thu Oct 31 17:00:46 UTC 2013


#3217: multiple start up problems
-------------------------------------+-------------------------------------
            Reporter:  jreed         |                        Owner:
                Type:  defect        |                       Status:  new
            Priority:  medium        |                    Milestone:  New
           Component:  Unclassified  |  Tasks
            Keywords:                |                   Resolution:
           Sensitive:  0             |                 CVSS Scoring:
         Sub-Project:  DNS           |              Defect Severity:  N/A
Estimated Difficulty:  0             |  Feature Depending on Ticket:
         Total Hours:  0             |          Add Hours to Ticket:  0
                                     |                    Internal?:  0
-------------------------------------+-------------------------------------

Comment (by jreed):

 Also I had lots of logs logged to log file:

 {{{
 2013-10-31 11:50:38.000 DEBUG [b10-cfgmgr.pycc/13117] PYCC_LNAME_RECEIVED
 received local name: 52728a5d_2 at t1.m.reedmedia.net
 2013-10-31 11:50:38.000 INFO  [b10-cfgmgr.cfgmgr/13117] CFGMGR_CONFIG_FILE
 Configuration manager starting with configuration file:
 /home/reed/opt/bind10/var/bind10/b10-config.db
 2013-10-31 11:50:38.000 DEBUG [b10-cfgmgr.config/13117]
 CONFIG_LOG_WILD_MATCH will use logging configuration for wildcard logger *
 2013-10-31 11:50:38.083 DEBUG [b10-msgq.msgq/12907] MSGQ_START Msgq
 version b10-msgq 20110127 (BIND 10 20130529) starting
 2013-10-31 11:50:38.083 DEBUG [b10-msgq.msgq/12907] MSGQ_LISTENER_SETUP
 Starting to listen on socket file
 '/home/reed/opt/bind10/var/bind10/msgq_socket'
 2013-10-31 11:50:38.083 DEBUG [b10-msgq.msgq/12907] MSGQ_LISTENER_STARTED
 Successfully started to listen
 2013-10-31 11:50:38.083 DEBUG [b10-msgq.msgq/12907] MSGQ_SOCKET_REGISTERED
 Registered a socket descriptor 7 with lname 52728a5d_1 at t1.m.reedmedia.net
 2013-10-31 11:50:38.083 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'type': 'getlname'}
 2013-10-31 11:50:38.083 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'instance': '*', 'type': 'subscribe', 'group': 'Init'}
 2013-10-31 11:50:38.083 DEBUG [b10-msgq.msgq/12907] MSGQ_SUBS_NEW_TARGET
 Creating new target for subscription to group 'Init' for instance '*'
 2013-10-31 11:50:38.083 DEBUG [b10-msgq.msgq/12907] MSGQ_SOCKET_REGISTERED
 Registered a socket descriptor 8 with lname 52728a5d_2 at t1.m.reedmedia.net
 2013-10-31 11:50:38.083 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'type': 'getlname'}
 2013-10-31 11:50:38.083 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'instance': '*', 'group': 'ConfigManager', 'type': 'subscribe'}
 2013-10-31 11:50:38.084 DEBUG [b10-msgq.msgq/12907] MSGQ_SUBS_NEW_TARGET
 Creating new target for subscription to group 'ConfigManager' for instance
 '*'
 2013-10-31 11:50:38.084 DEBUG [b10-msgq.msgq/12907] MSGQ_CFGMGR_SUBSCRIBED
 The config manager subscribed to message queue
 2013-10-31 11:50:38.084 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'instance': 'ConfigManager', 'group': 'Init', 'type':
 'subscribe'}
 2013-10-31 11:50:38.084 DEBUG [b10-msgq.msgq/12907] MSGQ_SUBS_NEW_TARGET
 Creating new target for subscription to group 'Init' for instance
 'ConfigManager'
 2013-10-31 11:50:38.084 DEBUG [b10-msgq.msgq/12907] MSGQ_SOCKET_REGISTERED
 Registered a socket descriptor 10 with lname 52728a5d_3 at t1.m.reedmedia.net
 2013-10-31 11:50:38.084 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'type': 'getlname'}
 2013-10-31 11:50:38.084 DEBUG [b10-msgq.pycc/12907] PYCC_LNAME_RECEIVED
 received local name: 52728a5d_3 at t1.m.reedmedia.net
 2013-10-31 11:50:38.084 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'instance': '*', 'type': 'subscribe', 'group': 'Msgq'}
 2013-10-31 11:50:38.084 DEBUG [b10-msgq.msgq/12907] MSGQ_SUBS_NEW_TARGET
 Creating new target for subscription to group 'Msgq' for instance '*'
 2013-10-31 11:50:38.084 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'instance': '*', 'type': 'subscribe', 'group': 'Logging'}
 2013-10-31 11:50:38.084 DEBUG [b10-msgq.msgq/12907] MSGQ_SUBS_NEW_TARGET
 Creating new target for subscription to group 'Logging' for instance '*'
 2013-10-31 11:50:38.084 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'group': 'ConfigManager',
 'from': '52728a5d_3 at t1.m.reedmedia.net', 'instance': '*', 'seq': 2,
 'type': 'send'}
 2013-10-31 11:50:38.084 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'type': 'send', 'from':
 '52728a5d_2 at t1.m.reedmedia.net', 'instance': '*', 'seq': 2, 'group':
 'Init'}
 2013-10-31 11:50:38.084 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'reply': 2, 'type': 'send', 'to':
 '52728a5d_3 at t1.m.reedmedia.net', 'from': '52728a5d_2 at t1.m.reedmedia.net',
 'instance': '*', 'seq': 3, 'group': 'ConfigManager'}
 2013-10-31 11:50:38.084 DEBUG [b10-msgq.config/12907]
 CONFIG_LOG_WILD_MATCH will use logging configuration for wildcard logger *
 2013-10-31 11:50:38.085 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'group': 'ConfigManager',
 'from': '52728a5d_3 at t1.m.reedmedia.net', 'instance': '*', 'seq': 3,
 'type': 'send'}
 2013-10-31 11:50:38.086 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'type': 'send', 'from':
 '52728a5d_2 at t1.m.reedmedia.net', 'instance': '*', 'seq': 4, 'group':
 'Cmdctl'}
 2013-10-31 11:50:38.086 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'reply': 3, 'type': 'send', 'to':
 '52728a5d_3 at t1.m.reedmedia.net', 'from': '52728a5d_2 at t1.m.reedmedia.net',
 'instance': '*', 'seq': 5, 'group': 'ConfigManager'}
 2013-10-31 11:50:38.087 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'group': 'ConfigManager',
 'from': '52728a5d_3 at t1.m.reedmedia.net', 'instance': '*', 'seq': 4,
 'type': 'send'}
 2013-10-31 11:50:38.088 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'reply': 4, 'type': 'send', 'to':
 '52728a5d_3 at t1.m.reedmedia.net', 'from': '52728a5d_2 at t1.m.reedmedia.net',
 'instance': '*', 'seq': 6, 'group': 'ConfigManager'}
 2013-10-31 11:50:38.088 DEBUG [b10-msgq.msgq/12907] MSGQ_CONFIG_DATA
 Received configuration update for the msgq: {'version': 3}
 2013-10-31 11:50:38.789 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'instance': '*', 'type': 'unsubscribe', 'group': 'Init'}
 2013-10-31 11:50:38.789 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'group':
 'notifications/cc_members', 'from': '52728a5d_3 at t1.m.reedmedia.net',
 'instance': '*', 'seq': 5, 'type': 'send'}
 2013-10-31 11:50:38.816 DEBUG [b10-msgq.msgq/12907] MSGQ_SOCKET_REGISTERED
 Registered a socket descriptor 15 with lname 52728a5e_4 at t1.m.reedmedia.net
 2013-10-31 11:50:38.817 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'group':
 'notifications/cc_members', 'from': '52728a5d_3 at t1.m.reedmedia.net',
 'instance': '*', 'seq': 6, 'type': 'send'}
 2013-10-31 11:50:38.817 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'type': 'getlname'}
 2013-10-31 11:50:38.818 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'instance': '*', 'type': 'subscribe', 'group': 'Init'}
 2013-10-31 11:50:38.818 DEBUG [b10-msgq.msgq/12907]
 MSGQ_SUBS_APPEND_TARGET Appending to existing target for subscription to
 group 'Init' for instance '*'
 2013-10-31 11:50:38.818 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'group':
 'notifications/cc_members', 'from': '52728a5d_3 at t1.m.reedmedia.net',
 'instance': '*', 'seq': 7, 'type': 'send'}
 2013-10-31 11:50:38.818 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'instance': '*', 'type': 'subscribe', 'group': 'Logging'}
 2013-10-31 11:50:38.818 DEBUG [b10-msgq.msgq/12907]
 MSGQ_SUBS_APPEND_TARGET Appending to existing target for subscription to
 group 'Logging' for instance '*'
 2013-10-31 11:50:38.819 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'group':
 'notifications/cc_members', 'from': '52728a5d_3 at t1.m.reedmedia.net',
 'instance': '*', 'seq': 8, 'type': 'send'}
 2013-10-31 11:50:38.819 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'type': 'send', 'from':
 '52728a5e_4 at t1.m.reedmedia.net', 'instance': '*', 'seq': 2, 'group':
 'ConfigManager'}
 2013-10-31 11:50:38.820 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'reply': 2, 'type': 'send', 'to':
 '52728a5e_4 at t1.m.reedmedia.net', 'from': '52728a5d_2 at t1.m.reedmedia.net',
 'instance': '*', 'seq': 7, 'group': 'ConfigManager'}
 2013-10-31 11:50:38.822 INFO  [b10-init.init/24108] BIND10_STARTING
 starting BIND10: bind10 20110223 (BIND 10 20130529)
 2013-10-31 11:50:38.822 DEBUG [b10-init.init/24108]
 BIND10_CHECK_MSGQ_ALREADY_RUNNING checking if msgq is already running
 2013-10-31 11:50:38.822 INFO  [b10-init.init/24108]
 BIND10_CONFIGURATOR_START bind10 component configurator is starting up
 2013-10-31 11:50:38.822 DEBUG [b10-init.init/24108]
 BIND10_CONFIGURATOR_BUILD building plan '{}' -> '{'cfgmgr': {'priority':
 198, 'kind': 'core', 'special': 'cfgmgr'}, 'msgq': {'priority': 199,
 'kind': 'core', 'special': 'msgq'}, 'sockcreator': {'priority': 200,
 'kind': 'core', 'special': 'sockcreator'}}'
 2013-10-31 11:50:38.822 DEBUG [b10-init.init/24108]
 BIND10_CONFIGURATOR_RUN running plan of 3 tasks
 2013-10-31 11:50:38.822 DEBUG [b10-init.init/24108]
 BIND10_CONFIGURATOR_TASK performing task start on Socket creator
 2013-10-31 11:50:38.822 INFO  [b10-init.init/24108] BIND10_COMPONENT_START
 component Socket creator is starting
 2013-10-31 11:50:38.822 INFO  [b10-init.init/24108]
 BIND10_SOCKCREATOR_INIT initializing socket creator parser
 2013-10-31 11:50:38.822 DEBUG [b10-init.init/24108]
 BIND10_STARTED_PROCESS_PID started b10-sockcreator (PID 18498)
 2013-10-31 11:50:38.822 DEBUG [b10-init.init/24108]
 BIND10_CONFIGURATOR_TASK performing task start on msgq
 2013-10-31 11:50:38.822 INFO  [b10-init.init/24108] BIND10_COMPONENT_START
 component msgq is starting
 2013-10-31 11:50:38.823 INFO  [b10-init.init/24108]
 BIND10_STARTING_PROCESS starting process b10-msgq
 2013-10-31 11:50:38.823 DEBUG [b10-init.init/24108]
 BIND10_STARTED_PROCESS_PID started b10-msgq (PID 12907)
 2013-10-31 11:50:38.823 DEBUG [b10-init.pycc/24108] PYCC_LNAME_RECEIVED
 received local name: 52728a5d_1 at t1.m.reedmedia.net
 2013-10-31 11:50:38.823 DEBUG [b10-init.init/24108]
 BIND10_CONFIGURATOR_TASK performing task start on cfgmgr
 2013-10-31 11:50:38.823 INFO  [b10-init.init/24108] BIND10_COMPONENT_START
 component cfgmgr is starting
 2013-10-31 11:50:38.823 INFO  [b10-init.init/24108]
 BIND10_STARTING_PROCESS starting process b10-cfgmgr
 2013-10-31 11:50:38.823 DEBUG [b10-init.init/24108]
 BIND10_STARTED_PROCESS_PID started b10-cfgmgr (PID 13117)
 2013-10-31 11:50:38.823 DEBUG [b10-init.init/24108] BIND10_WAIT_CFGMGR
 waiting for configuration manager process to initialize
 2013-10-31 11:50:38.823 INFO  [b10-init.init/24108] BIND10_STARTING_CC
 starting configuration/command session
 2013-10-31 11:50:38.823 DEBUG [b10-init.pycc/24108] PYCC_LNAME_RECEIVED
 received local name: 52728a5e_4 at t1.m.reedmedia.net
 2013-10-31 11:50:38.823 DEBUG [b10-init.config/24108]
 CONFIG_LOG_WILD_MATCH will use logging configuration for wildcard logger *
 2013-10-31 11:50:38.824 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'type': 'send', 'from':
 '52728a5e_4 at t1.m.reedmedia.net', 'instance': '*', 'seq': 3, 'group':
 'ConfigManager'}
 2013-10-31 11:50:38.836 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'type': 'send', 'from':
 '52728a5d_2 at t1.m.reedmedia.net', 'instance': '*', 'seq': 8, 'group':
 'Cmdctl'}
 2013-10-31 11:50:38.837 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'reply': 3, 'type': 'send', 'to':
 '52728a5e_4 at t1.m.reedmedia.net', 'from': '52728a5d_2 at t1.m.reedmedia.net',
 'instance': '*', 'seq': 9, 'group': 'ConfigManager'}
 2013-10-31 11:50:38.837 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'type': 'send', 'from':
 '52728a5e_4 at t1.m.reedmedia.net', 'instance': '*', 'seq': 4, 'group':
 'ConfigManager'}
 2013-10-31 11:50:38.838 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'reply': 4, 'type': 'send', 'to':
 '52728a5e_4 at t1.m.reedmedia.net', 'from': '52728a5d_2 at t1.m.reedmedia.net',
 'instance': '*', 'seq': 10, 'group': 'ConfigManager'}
 2013-10-31 11:50:38.839 DEBUG [b10-init.init/24108] BIND10_STARTED_CC
 started configuration/command session
 2013-10-31 11:50:38.839 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': True, 'to': '*', 'type': 'send', 'from':
 '52728a5e_4 at t1.m.reedmedia.net', 'instance': '*', 'seq': 5, 'group':
 'Msgq'}
 2013-10-31 11:50:38.840 DEBUG [b10-msgq.msgq/12907] MSGQ_COMMAND Running
 command AreYouThere? with arguments None
 2013-10-31 11:50:38.840 ERROR [b10-msgq.msgq/12907] MSGQ_COMMAND_UNKNOWN
 Unknown command 'AreYouThere?'
 2013-10-31 11:50:38.840 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'group': 'Msgq', 'to': '52728a5e_4 at t1.m.reedmedia.net', 'reply':
 5, 'from': '52728a5d_3 at t1.m.reedmedia.net', 'instance': '*', 'seq': 9,
 'type': 'send'}
 2013-10-31 11:50:38.841 INFO  [b10-init.init/24108]
 BIND10_READING_INIT_CONFIGURATION reading b10-init configuration
 2013-10-31 11:50:38.841 INFO  [b10-init.init/24108]
 BIND10_CONFIGURATOR_RECONFIGURE reconfiguring running components
 2013-10-31 11:50:38.841 DEBUG [b10-init.init/24108]
 BIND10_CONFIGURATOR_BUILD building plan '{'cfgmgr': ({'priority': 198,
 'kind': 'core', 'special': 'cfgmgr'}, <isc.bind10.special_component.CfgMgr
 object at 0x7f7ff5d720a0>), 'msgq': ({'priority': 199, 'kind': 'core',
 'special': 'msgq'}, <isc.bind10.special_component.Msgq object at
 0x7f7ff5d720e0>), 'sockcreator': ({'priority': 200, 'kind': 'core',
 'special': 'sockcreator'}, <isc.bind10.special_component.SockCreator
 object at 0x7f7ff5d72120>)}' -> '{'b10-resolver': {'priority': 10, 'kind':
 'needed', 'special': 'resolver'}, 'b10-stats-httpd': {'kind':
 'dispensable', 'address': 'StatsHttpd'}, 'cfgmgr': {'priority': 198,
 'kind': 'core', 'special': 'cfgmgr'}, 'b10-auth': {'priority': 10, 'kind':
 'needed', 'special': 'auth'}, 'b10-cmdctl': {'kind': 'needed', 'special':
 'cmdctl'}, 'b10-zonemgr': {'kind': 'dispensable'}, 'sockcreator':
 {'priority': 200, 'kind': 'core', 'special': 'sockcreator'}, 'b10-stats':
 {'kind': 'dispensable', 'address': 'Stats'}, 'msgq': {'priority': 199,
 'kind': 'core', 'special': 'msgq'}, 'b10-ddns': {'kind': 'dispensable',
 'address': 'DDNS'}}'
 2013-10-31 11:50:38.841 DEBUG [b10-init.init/24108]
 BIND10_CONFIGURATOR_RUN running plan of 7 tasks
 2013-10-31 11:50:38.842 DEBUG [b10-init.init/24108]
 BIND10_CONFIGURATOR_TASK performing task start on b10-resolver
 2013-10-31 11:50:38.842 INFO  [b10-init.init/24108] BIND10_COMPONENT_START
 component b10-resolver is starting
 2013-10-31 11:50:38.842 INFO  [b10-init.init/24108]
 BIND10_STARTING_PROCESS starting process b10-resolver
 2013-10-31 11:50:38.847 DEBUG [b10-init.init/24108]
 BIND10_STARTED_PROCESS_PID started b10-resolver (PID 6358)
 2013-10-31 11:50:38.848 DEBUG [b10-init.init/24108]
 BIND10_CONFIGURATOR_TASK performing task start on b10-auth
 2013-10-31 11:50:38.848 INFO  [b10-init.init/24108] BIND10_COMPONENT_START
 component b10-auth is starting
 2013-10-31 11:50:38.848 INFO  [b10-init.init/24108]
 BIND10_STARTING_PROCESS starting process b10-auth
 2013-10-31 11:50:38.854 DEBUG [b10-init.init/24108]
 BIND10_STARTED_PROCESS_PID started b10-auth (PID 9101)
 2013-10-31 11:50:38.854 DEBUG [b10-init.init/24108]
 BIND10_CONFIGURATOR_TASK performing task start on b10-stats-httpd
 2013-10-31 11:50:38.854 INFO  [b10-init.init/24108] BIND10_COMPONENT_START
 component b10-stats-httpd is starting
 2013-10-31 11:50:38.854 INFO  [b10-init.init/24108]
 BIND10_STARTING_PROCESS starting process b10-stats-httpd
 2013-10-31 11:50:38.860 DEBUG [b10-init.init/24108]
 BIND10_STARTED_PROCESS_PID started b10-stats-httpd (PID 10262)
 2013-10-31 11:50:38.861 DEBUG [b10-init.init/24108]
 BIND10_CONFIGURATOR_TASK performing task start on b10-cmdctl
 2013-10-31 11:50:38.861 INFO  [b10-init.init/24108] BIND10_COMPONENT_START
 component b10-cmdctl is starting
 2013-10-31 11:50:38.861 INFO  [b10-init.init/24108]
 BIND10_STARTING_PROCESS starting process b10-cmdctl
 2013-10-31 11:50:38.984 DEBUG [b10-msgq.msgq/12907] MSGQ_SOCKET_REGISTERED
 Registered a socket descriptor 16 with lname 52728a5e_5 at t1.m.reedmedia.net
 2013-10-31 11:50:38.985 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'group':
 'notifications/cc_members', 'from': '52728a5d_3 at t1.m.reedmedia.net',
 'instance': '*', 'seq': 10, 'type': 'send'}
 2013-10-31 11:50:38.985 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'type': 'getlname'}
 2013-10-31 11:50:38.987 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'instance': '*', 'type': 'subscribe', 'group': 'Resolver'}
 2013-10-31 11:50:38.987 DEBUG [b10-msgq.msgq/12907] MSGQ_SUBS_NEW_TARGET
 Creating new target for subscription to group 'Resolver' for instance '*'
 2013-10-31 11:50:38.987 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'group':
 'notifications/cc_members', 'from': '52728a5d_3 at t1.m.reedmedia.net',
 'instance': '*', 'seq': 11, 'type': 'send'}
 2013-10-31 11:50:38.988 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'type': 'send', 'from':
 '52728a5e_5 at t1.m.reedmedia.net', 'instance': '*', 'seq': 0, 'group':
 'ConfigManager'}
 2013-10-31 11:50:38.990 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'type': 'send', 'from':
 '52728a5d_2 at t1.m.reedmedia.net', 'instance': '*', 'seq': 11, 'group':
 'Cmdctl'}
 2013-10-31 11:50:38.990 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'reply': 0, 'type': 'send', 'to':
 '52728a5e_5 at t1.m.reedmedia.net', 'from': '52728a5d_2 at t1.m.reedmedia.net',
 'instance': '*', 'seq': 12, 'group': 'ConfigManager'}
 2013-10-31 11:50:38.991 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'type': 'send', 'from':
 '52728a5e_5 at t1.m.reedmedia.net', 'instance': '*', 'seq': 1, 'group':
 'ConfigManager'}
 2013-10-31 11:50:38.992 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'reply': 1, 'type': 'send', 'to':
 '52728a5e_5 at t1.m.reedmedia.net', 'from': '52728a5d_2 at t1.m.reedmedia.net',
 'instance': '*', 'seq': 13, 'group': 'ConfigManager'}
 2013-10-31 11:50:39.001 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'type': 'send', 'from':
 '52728a5e_5 at t1.m.reedmedia.net', 'instance': '*', 'seq': 2, 'group':
 'ConfigManager'}
 2013-10-31 11:50:39.002 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'reply': 2, 'type': 'send', 'to':
 '52728a5e_5 at t1.m.reedmedia.net', 'from': '52728a5d_2 at t1.m.reedmedia.net',
 'instance': '*', 'seq': 14, 'group': 'ConfigManager'}
 2013-10-31 11:50:39.004 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'type': 'send', 'from':
 '52728a5e_5 at t1.m.reedmedia.net', 'instance': '*', 'seq': 3, 'group':
 'ConfigManager'}
 2013-10-31 11:50:39.005 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'reply': 3, 'type': 'send', 'to':
 '52728a5e_5 at t1.m.reedmedia.net', 'from': '52728a5d_2 at t1.m.reedmedia.net',
 'instance': '*', 'seq': 15, 'group': 'ConfigManager'}
 2013-10-31 11:50:39.016 DEBUG [b10-msgq.msgq/12907] MSGQ_CLOSE_ON_RECV
 Reading from socket canceled as it's closed: FD=16
 2013-10-31 11:50:39.016 DEBUG [b10-msgq.msgq/12907] MSGQ_SOCK_CLOSE
 Closing socket fd 16
 2013-10-31 11:50:39.017 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'group':
 'notifications/cc_members', 'from': '52728a5d_3 at t1.m.reedmedia.net',
 'instance': '*', 'seq': 12, 'type': 'send'}
 2013-10-31 11:50:39.017 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'group':
 'notifications/cc_members', 'from': '52728a5d_3 at t1.m.reedmedia.net',
 'instance': '*', 'seq': 13, 'type': 'send'}
 2013-10-31 11:50:39.020 DEBUG [b10-init.init/24108]
 BIND10_STARTED_PROCESS_PID started b10-cmdctl (PID 3921)
 2013-10-31 11:50:39.022 DEBUG [b10-init.init/24108]
 BIND10_CONFIGURATOR_TASK performing task start on b10-zonemgr
 2013-10-31 11:50:39.022 INFO  [b10-init.init/24108] BIND10_COMPONENT_START
 component b10-zonemgr is starting
 2013-10-31 11:50:39.024 INFO  [b10-init.init/24108]
 BIND10_STARTING_PROCESS starting process b10-zonemgr
 2013-10-31 11:50:39.110 DEBUG [b10-init.init/24108]
 BIND10_STARTED_PROCESS_PID started b10-zonemgr (PID 17543)
 2013-10-31 11:50:39.111 DEBUG [b10-init.init/24108]
 BIND10_CONFIGURATOR_TASK performing task start on b10-stats
 2013-10-31 11:50:39.111 INFO  [b10-init.init/24108] BIND10_COMPONENT_START
 component b10-stats is starting
 2013-10-31 11:50:39.111 INFO  [b10-init.init/24108]
 BIND10_STARTING_PROCESS starting process b10-stats
 2013-10-31 11:50:39.221 DEBUG [b10-init.init/24108]
 BIND10_STARTED_PROCESS_PID started b10-stats (PID 5408)
 2013-10-31 11:50:39.221 DEBUG [b10-init.init/24108]
 BIND10_CONFIGURATOR_TASK performing task start on b10-ddns
 2013-10-31 11:50:39.221 INFO  [b10-init.init/24108] BIND10_COMPONENT_START
 component b10-ddns is starting
 2013-10-31 11:50:39.221 INFO  [b10-init.init/24108]
 BIND10_STARTING_PROCESS starting process b10-ddns
 2013-10-31 11:50:39.228 DEBUG [b10-init.init/24108]
 BIND10_STARTED_PROCESS_PID started b10-ddns (PID 11773)
 2013-10-31 11:50:39.233 INFO  [b10-init.init/24108]
 BIND10_STARTUP_COMPLETE BIND 10 started
 2013-10-31 11:50:39.233 INFO  [b10-init.init/24108] BIND10_PROCESS_ENDED
 process 6358 of b10-resolver ended with status 139
 2013-10-31 11:50:39.233 ERROR [b10-init.init/24108]
 BIND10_COMPONENT_FAILED component b10-resolver (pid 6358) failed: process
 dumped core with exit status 139 (killed by signal 11: SIGSEGV)
 2013-10-31 11:50:39.234 FATAL [b10-init.init/24108]
 BIND10_COMPONENT_UNSATISFIED component b10-resolver is required to run and
 failed
 2013-10-31 11:50:39.234 INFO  [b10-init.init/24108] BIND10_SHUTDOWN
 stopping the server
 2013-10-31 11:50:39.235 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'type': 'send', 'from':
 '52728a5e_4 at t1.m.reedmedia.net', 'instance': '*', 'seq': 6, 'group':
 'ConfigManager'}
 2013-10-31 11:50:39.236 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'type': 'send', 'from':
 '52728a5d_2 at t1.m.reedmedia.net', 'instance': '*', 'seq': 16, 'group':
 'Cmdctl'}
 2013-10-31 11:50:39.328 INFO  [b10-init.init/24108]
 BIND10_CONFIGURATOR_STOP bind10 component configurator is shutting down
 2013-10-31 11:50:39.328 DEBUG [b10-init.init/24108]
 BIND10_CONFIGURATOR_BUILD building plan '{'b10-resolver': ({'priority':
 10, 'kind': 'needed', 'special': 'resolver'},
 <isc.bind10.special_component.Resolver object at 0x7f7ff5950560>), 'b10
 -stats-httpd': ({'kind': 'dispensable', 'address': 'StatsHttpd'},
 <isc.bind10.component.Component object at 0x7f7ff59505a0>), 'cfgmgr':
 ({'priority': 198, 'kind': 'core', 'special': 'cfgmgr'},
 <isc.bind10.special_component.CfgMgr object at 0x7f7ff5d720a0>),
 'b10-auth': ({'priority': 10, 'kind': 'needed', 'special': 'auth'},
 <isc.bind10.special_component.Auth object at 0x7f7ff5950620>),
 'b10-zonemgr': ({'kind': 'dispensable'}, <isc.bind10.component.Component
 object at 0x7f7ff59650e0>), 'b10-cmdctl': ({'kind': 'needed', 'special':
 'cmdctl'}, <isc.bind10.special_component.CmdCtl object at
 0x7f7ff59650a0>), 'b10-ddns': ({'kind': 'dispensable', 'address': 'DDNS'},
 <isc.bind10.component.Component object at 0x7f7ff59651a0>), 'b10-stats':
 ({'kind': 'dispensable', 'address': 'Stats'},
 <isc.bind10.component.Component object at 0x7f7ff5965160>), 'msgq':
 ({'priority': 199, 'kind': 'core', 'special': 'msgq'},
 <isc.bind10.special_component.Msgq object at 0x7f7ff5d720e0>),
 'sockcreator': ({'priority': 200, 'kind': 'core', 'special':
 'sockcreator'}, <isc.bind10.special_component.SockCreator object at
 0x7f7ff5d72120>)}' -> '{}'
 2013-10-31 11:50:39.328 DEBUG [b10-init.init/24108]
 BIND10_CONFIGURATOR_RUN running plan of 9 tasks
 2013-10-31 11:50:39.328 DEBUG [b10-init.init/24108]
 BIND10_CONFIGURATOR_TASK performing task stop on b10-stats-httpd
 2013-10-31 11:50:39.329 INFO  [b10-init.init/24108] BIND10_COMPONENT_STOP
 component b10-stats-httpd is being stopped
 2013-10-31 11:50:39.329 INFO  [b10-init.init/24108] BIND10_STOP_PROCESS
 asking b10-stats-httpd to shut down
 2013-10-31 11:50:39.329 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'type': 'send', 'from':
 '52728a5d_1 at t1.m.reedmedia.net', 'instance': 'StatsHttpd', 'seq': 2,
 'group': 'StatsHttpd'}
 2013-10-31 11:50:39.407 DEBUG [b10-msgq.msgq/12907] MSGQ_SOCKET_REGISTERED
 Registered a socket descriptor 16 with lname 52728a5f_6 at t1.m.reedmedia.net
 2013-10-31 11:50:39.408 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'group':
 'notifications/cc_members', 'from': '52728a5d_3 at t1.m.reedmedia.net',
 'instance': '*', 'seq': 14, 'type': 'send'}
 2013-10-31 11:50:39.597 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'type': 'getlname'}
 2013-10-31 11:50:39.630 DEBUG [b10-msgq.msgq/12907] MSGQ_SOCKET_REGISTERED
 Registered a socket descriptor 17 with lname 52728a5f_7 at t1.m.reedmedia.net
 2013-10-31 11:50:39.631 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'group':
 'notifications/cc_members', 'from': '52728a5d_3 at t1.m.reedmedia.net',
 'instance': '*', 'seq': 15, 'type': 'send'}
 2013-10-31 11:50:39.632 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'instance': '*', 'type': 'subscribe', 'group': 'StatsHttpd'}
 2013-10-31 11:50:39.632 DEBUG [b10-msgq.msgq/12907] MSGQ_SUBS_NEW_TARGET
 Creating new target for subscription to group 'StatsHttpd' for instance
 '*'
 2013-10-31 11:50:39.632 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'group':
 'notifications/cc_members', 'from': '52728a5d_3 at t1.m.reedmedia.net',
 'instance': '*', 'seq': 16, 'type': 'send'}
 2013-10-31 11:50:39.633 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'type': 'getlname'}
 2013-10-31 11:50:39.634 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'instance': '*', 'type': 'subscribe', 'group': 'Logging'}
 2013-10-31 11:50:39.634 DEBUG [b10-msgq.msgq/12907]
 MSGQ_SUBS_APPEND_TARGET Appending to existing target for subscription to
 group 'Logging' for instance '*'
 2013-10-31 11:50:39.634 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'group':
 'notifications/cc_members', 'from': '52728a5d_3 at t1.m.reedmedia.net',
 'instance': '*', 'seq': 17, 'type': 'send'}
 2013-10-31 11:50:39.636 DEBUG [b10-msgq.msgq/12907] MSGQ_SOCKET_REGISTERED
 Registered a socket descriptor 18 with lname 52728a5f_8 at t1.m.reedmedia.net
 2013-10-31 11:50:39.636 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'group':
 'notifications/cc_members', 'from': '52728a5d_3 at t1.m.reedmedia.net',
 'instance': '*', 'seq': 18, 'type': 'send'}
 2013-10-31 11:50:39.637 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'group': 'ConfigManager',
 'from': '52728a5f_6 at t1.m.reedmedia.net', 'instance': '*', 'seq': 2,
 'type': 'send'}
 2013-10-31 11:50:39.638 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'reply': 2, 'type': 'send', 'to':
 '52728a5f_6 at t1.m.reedmedia.net', 'from': '52728a5d_2 at t1.m.reedmedia.net',
 'instance': '*', 'seq': 17, 'group': 'ConfigManager'}
 2013-10-31 11:50:39.638 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'type': 'getlname'}
 2013-10-31 11:50:39.640 DEBUG [b10-stats-httpd.stats-httpd/10262]
 STATSHTTPD_STARTING_CC_SESSION starting cc session
 2013-10-31 11:50:39.641 DEBUG [b10-stats-httpd.pycc/10262]
 PYCC_LNAME_RECEIVED received local name: 52728a5f_6 at t1.m.reedmedia.net
 2013-10-31 11:50:39.641 DEBUG [b10-stats-httpd.config/10262]
 CONFIG_LOG_WILD_MATCH will use logging configuration for wildcard logger *
 2013-10-31 11:50:39.642 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'group': 'ConfigManager',
 'from': '52728a5f_6 at t1.m.reedmedia.net', 'instance': '*', 'seq': 3,
 'type': 'send'}
 2013-10-31 11:50:39.643 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'type': 'send', 'from':
 '52728a5d_2 at t1.m.reedmedia.net', 'instance': '*', 'seq': 18, 'group':
 'Cmdctl'}
 2013-10-31 11:50:39.643 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'reply': 3, 'type': 'send', 'to':
 '52728a5f_6 at t1.m.reedmedia.net', 'from': '52728a5d_2 at t1.m.reedmedia.net',
 'instance': '*', 'seq': 19, 'group': 'ConfigManager'}
 2013-10-31 11:50:39.644 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'instance': '*', 'type': 'subscribe', 'group': 'Cmdctl'}
 2013-10-31 11:50:39.644 DEBUG [b10-msgq.msgq/12907] MSGQ_SUBS_NEW_TARGET
 Creating new target for subscription to group 'Cmdctl' for instance '*'
 2013-10-31 11:50:39.644 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'group':
 'notifications/cc_members', 'from': '52728a5d_3 at t1.m.reedmedia.net',
 'instance': '*', 'seq': 19, 'type': 'send'}
 2013-10-31 11:50:39.645 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'group': 'ConfigManager',
 'from': '52728a5f_6 at t1.m.reedmedia.net', 'instance': '*', 'seq': 4,
 'type': 'send'}
 2013-10-31 11:50:39.646 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'reply': 4, 'type': 'send', 'to':
 '52728a5f_6 at t1.m.reedmedia.net', 'from': '52728a5d_2 at t1.m.reedmedia.net',
 'instance': '*', 'seq': 20, 'group': 'ConfigManager'}
 2013-10-31 11:50:39.647 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'instance': '*', 'type': 'subscribe', 'group': 'Logging'}
 2013-10-31 11:50:39.647 DEBUG [b10-msgq.msgq/12907]
 MSGQ_SUBS_APPEND_TARGET Appending to existing target for subscription to
 group 'Logging' for instance '*'
 2013-10-31 11:50:39.648 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'group':
 'notifications/cc_members', 'from': '52728a5d_3 at t1.m.reedmedia.net',
 'instance': '*', 'seq': 20, 'type': 'send'}
 2013-10-31 11:50:39.648 DEBUG [b10-stats-httpd.stats-httpd/10262]
 STATSHTTPD_HANDLE_CONFIG reading configuration: {'listen_on': [{'address':
 '127.0.0.1', 'port': 8000}, {'address': '192.168.1.2', 'port': 8000}]}
 2013-10-31 11:50:39.659 DEBUG [b10-init.init/24108]
 BIND10_CONFIGURATOR_TASK performing task stop on cfgmgr
 2013-10-31 11:50:39.659 INFO  [b10-init.init/24108] BIND10_COMPONENT_STOP
 component cfgmgr is being stopped
 2013-10-31 11:50:39.659 INFO  [b10-init.init/24108] BIND10_STOP_PROCESS
 asking cfgmgr to shut down
 2013-10-31 11:50:39.660 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'type': 'send', 'from':
 '52728a5d_1 at t1.m.reedmedia.net', 'instance': 'ConfigManager', 'seq': 3,
 'group': 'ConfigManager'}
 2013-10-31 11:50:39.660 INFO  [b10-cfgmgr.cfgmgr/13117]
 CFGMGR_STOPPED_BY_COMMAND received shutdown command, shutting down
 2013-10-31 11:50:39.665 INFO  [b10-stats-httpd.stats-httpd/10262]
 STATSHTTPD_STARTED listening on 127.0.0.1:8000
 2013-10-31 11:50:39.666 INFO  [b10-stats-httpd.stats-httpd/10262]
 STATSHTTPD_STARTED listening on 192.168.1.2:8000
 2013-10-31 11:50:39.681 DEBUG [b10-init.init/24108]
 BIND10_CONFIGURATOR_TASK performing task stop on b10-auth
 2013-10-31 11:50:39.681 INFO  [b10-init.init/24108] BIND10_COMPONENT_STOP
 component b10-auth is being stopped
 2013-10-31 11:50:39.681 INFO  [b10-init.init/24108] BIND10_STOP_PROCESS
 asking b10-auth to shut down
 2013-10-31 11:50:39.681 DEBUG [b10-init.init/24108]
 BIND10_CONFIGURATOR_TASK performing task stop on b10-zonemgr
 2013-10-31 11:50:39.681 INFO  [b10-init.init/24108] BIND10_COMPONENT_STOP
 component b10-zonemgr is being stopped
 2013-10-31 11:50:39.681 INFO  [b10-init.init/24108] BIND10_STOP_PROCESS
 asking b10-zonemgr to shut down
 2013-10-31 11:50:39.681 DEBUG [b10-init.init/24108]
 BIND10_CONFIGURATOR_TASK performing task stop on b10-cmdctl
 2013-10-31 11:50:39.682 INFO  [b10-init.init/24108] BIND10_COMPONENT_STOP
 component b10-cmdctl is being stopped
 2013-10-31 11:50:39.682 INFO  [b10-init.init/24108] BIND10_STOP_PROCESS
 asking b10-cmdctl to shut down
 2013-10-31 11:50:39.682 DEBUG [b10-init.init/24108]
 BIND10_CONFIGURATOR_TASK performing task stop on b10-ddns
 2013-10-31 11:50:39.682 INFO  [b10-init.init/24108] BIND10_COMPONENT_STOP
 component b10-ddns is being stopped
 2013-10-31 11:50:39.682 INFO  [b10-init.init/24108] BIND10_STOP_PROCESS
 asking b10-ddns to shut down
 2013-10-31 11:50:39.682 DEBUG [b10-init.init/24108]
 BIND10_CONFIGURATOR_TASK performing task stop on b10-stats
 2013-10-31 11:50:39.682 INFO  [b10-init.init/24108] BIND10_COMPONENT_STOP
 component b10-stats is being stopped
 2013-10-31 11:50:39.683 INFO  [b10-init.init/24108] BIND10_STOP_PROCESS
 asking b10-stats to shut down
 2013-10-31 11:50:39.683 DEBUG [b10-init.init/24108]
 BIND10_CONFIGURATOR_TASK performing task stop on msgq
 2013-10-31 11:50:39.683 INFO  [b10-init.init/24108] BIND10_COMPONENT_STOP
 component msgq is being stopped
 2013-10-31 11:50:39.683 DEBUG [b10-init.init/24108]
 BIND10_CONFIGURATOR_TASK performing task stop on Socket creator
 2013-10-31 11:50:39.683 INFO  [b10-init.init/24108] BIND10_COMPONENT_STOP
 component Socket creator is being stopped
 2013-10-31 11:50:39.683 INFO  [b10-init.init/24108]
 BIND10_SOCKCREATOR_TERMINATE terminating socket creator
 2013-10-31 11:50:39.684 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'type': 'send', 'from':
 '52728a5f_8 at t1.m.reedmedia.net', 'instance': '*', 'seq': 2, 'group':
 'ConfigManager'}
 2013-10-31 11:50:39.685 WARN  [b10-msgq.msgq/12907] MSGQ_CLOSE_ON_SEND
 Sending to socket failed as already closed (okay to ignore on shutdown):
 FD=8
 2013-10-31 11:50:39.685 DEBUG [b10-msgq.msgq/12907] MSGQ_SOCK_CLOSE
 Closing socket fd 8
 2013-10-31 11:50:39.685 DEBUG [b10-msgq.msgq/12907] MSGQ_RECV_HDR Received
 header: {'want_answer': False, 'to': '*', 'type': 'send', 'from':
 '52728a5d_1 at t1.m.reedmedia.net', 'instance': 'Auth', 'seq': 4, 'group':
 'Auth'}
 2013-10-31 11:50:39.743 DEBUG [b10-msgq.msgq/12907] MSGQ_SHUTDOWN Stopping
 Msgq
 2013-10-31 11:50:39.782 INFO  [b10-msgq.msgq/12907] MSGQ_EXITING exiting
 2013-10-31 11:50:39.808 FATAL [b10-stats-httpd.util/10262]
 PYTHON_UNHANDLED_EXCEPTION program terminated with exception
 ProtocolError: Read of 0 bytes: connection closed. More info in
 /tmp/tmpe4vce1
 2013-10-31 11:50:40.698 INFO  [b10-init.init/24108] BIND10_PROCESS_ENDED
 process 17543 of b10-zonemgr ended with status 256
 2013-10-31 11:50:40.699 INFO  [b10-init.init/24108] BIND10_PROCESS_ENDED
 process 11773 of b10-ddns ended with status 0
 2013-10-31 11:50:40.699 INFO  [b10-init.init/24108] BIND10_PROCESS_ENDED
 process 3921 of b10-cmdctl ended with status 256
 2013-10-31 11:50:40.699 INFO  [b10-init.init/24108] BIND10_PROCESS_ENDED
 process 10262 of b10-stats-httpd ended with status 256
 2013-10-31 11:50:40.700 INFO  [b10-init.init/24108] BIND10_PROCESS_ENDED
 process 12907 of msgq ended with status 0
 2013-10-31 11:50:40.700 INFO  [b10-init.init/24108] BIND10_PROCESS_ENDED
 process 9101 of b10-auth ended with status 256
 2013-10-31 11:50:40.700 INFO  [b10-init.init/24108] BIND10_PROCESS_ENDED
 process 5408 of b10-stats ended with status 256
 2013-10-31 11:50:40.700 INFO  [b10-init.init/24108] BIND10_PROCESS_ENDED
 process 18498 of Socket creator ended with status 0
 2013-10-31 11:50:40.701 INFO  [b10-init.init/24108] BIND10_PROCESS_ENDED
 process 13117 of cfgmgr ended with status 0
 2013-10-31 11:50:40.701 INFO  [b10-init.init/24108]
 BIND10_SHUTDOWN_COMPLETE all processes ended, shutdown complete
 }}}

 And that referenced /tmp/tmpe4vce1
 {{{
 Traceback (most recent call last):
   File "/home/reed/opt/bind10/lib/python3.3/site-
 packages/isc/util/traceback_handler.py", line 33, in traceback_handler
     return main()
   File "/home/reed/opt/bind10/libexec/bind10/b10-stats-httpd", line 612,
 in main
     stats_httpd.start()
   File "/home/reed/opt/bind10/libexec/bind10/b10-stats-httpd", line 371,
 in start
     self.mccs.check_command(nonblock=False)
   File "/home/reed/opt/bind10/lib/python3.3/site-
 packages/isc/config/ccsession.py", line 290, in check_command
     msg, env = self._session.group_recvmsg(nonblock)
   File "/home/reed/opt/bind10/lib/python3.3/site-
 packages/isc/cc/session.py", line 306, in group_recvmsg
     env, msg  = self.recvmsg(nonblock, seq)
   File "/home/reed/opt/bind10/lib/python3.3/site-
 packages/isc/cc/session.py", line 139, in recvmsg
     data = self._receive_full_buffer(nonblock)
   File "/home/reed/opt/bind10/lib/python3.3/site-
 packages/isc/cc/session.py", line 223, in _receive_full_buffer
     self._receive_len_data()
   File "/home/reed/opt/bind10/lib/python3.3/site-
 packages/isc/cc/session.py", line 183, in _receive_len_data
     new_data = self._receive_bytes(self._recv_len_size)
   File "/home/reed/opt/bind10/lib/python3.3/site-
 packages/isc/cc/session.py", line 171, in _receive_bytes
     raise ProtocolError("Read of 0 bytes: connection closed")
 isc.cc.session.ProtocolError: Read of 0 bytes: connection closed
 }}}

-- 
Ticket URL: <http://bind10.isc.org/ticket/3217#comment:1>
BIND 10 Development <http://bind10.isc.org>
BIND 10 Development


More information about the bind10-tickets mailing list