07/07 15:02:18 (fd:3) (pid:1436) LOGS_USE_TIMESTAMP is undefined, using default value of False
07/07 15:02:18 (fd:3) (pid:1436) Locale: English_United States.1252
07/07 15:02:18 (fd:3) (pid:1436) config: using subsystem 'NEGOTIATOR', local ''
07/07 15:02:18 (fd:3) (pid:1436) Finding local host information, calling gethostname()
07/07 15:02:18 (fd:3) (pid:1436) gethostname() returned a host with no domain "electrabel"
07/07 15:02:18 (fd:3) (pid:1436) Trying to find full hostname and IP addr for "electrabel"
07/07 15:02:18 (fd:3) (pid:1436) Calling gethostbyname(electrabel)
07/07 15:02:18 (fd:3) (pid:1436) Found IP addr in hostent: 172.17.7.223
07/07 15:02:18 (fd:3) (pid:1436) Trying to find full hostname from hostent
07/07 15:02:18 (fd:3) (pid:1436) Main name in hostent "electrabel.pylos.logilab.fr" is fully qualified
07/07 15:02:18 (fd:3) (pid:1436) Trying to initialize local IP address (config file not read)
07/07 15:02:18 (fd:3) (pid:1436) Already found IP with gethostbyname()
07/07 15:02:18 (fd:3) (pid:1436) NET_REMAP_ENABLE is undefined, using default value of False
07/07 15:02:18 (fd:3) (pid:1436) ENABLE_RUNTIME_CONFIG is undefined, using default value of False
07/07 15:02:18 (fd:3) (pid:1436) ENABLE_PERSISTENT_CONFIG is undefined, using default value of False
07/07 15:02:18 (fd:3) (pid:1436) Trying to initialize local IP address (after reading config)
07/07 15:02:18 (fd:3) (pid:1436) NETWORK_INTERFACE not in config file, using existing value
07/07 15:02:18 (fd:3) (pid:1436) ABORT_ON_EXCEPTION is undefined, using default value of False
07/07 15:02:18 (fd:3) (pid:1436) Config 'NEGOTIATOR_DEBUG': no prefix ==> 'D_MATCH'
07/07 15:02:18 (fd:3) (pid:1436) Config 'NEGOTIATOR_LOG': no prefix ==> '$(LOG)/NegotiatorLog'
07/07 15:02:18 (fd:3) (pid:1436) Config 'MAX_NEGOTIATOR_LOG': no prefix ==> '1000000'
07/07 15:02:18 (fd:3) (pid:1436) Config 'NEGOTIATOR_MATCH_LOG': no prefix ==> '$(LOG)/MatchLog'
07/07 15:02:18 (fd:3) (pid:1436) Config 'MAX_NEGOTIATOR_MATCH_LOG': no prefix ==> '1000000'
07/07 15:02:18 (fd:3) (pid:1436) PRIV_UNKNOWN --> PRIV_CONDOR at ..\src\condor_daemon_core.V6\daemon_core_main.cpp:1834
07/07 15:02:18 (fd:3) (pid:1436) KEYCACHE: created: 00C000B0
07/07 15:02:18 (fd:3) (pid:1436) WANT_UDP_COMMAND_SOCKET is undefined, using default value of True
07/07 15:02:18 (fd:3) (pid:1436) ******************************************************
07/07 15:02:18 (fd:3) (pid:1436) ** condor_negotiator.exe (CONDOR_NEGOTIATOR) STARTING UP
07/07 15:02:18 (fd:3) (pid:1436) ** C:\condor\bin\condor_negotiator.exe
07/07 15:02:18 (fd:3) (pid:1436) ** SubsystemInfo: name=NEGOTIATOR type=NEGOTIATOR(4) class=DAEMON(1)
07/07 15:02:18 (fd:3) (pid:1436) ** Configuration: subsystem:NEGOTIATOR local:<NONE> class:DAEMON
07/07 15:02:18 (fd:3) (pid:1436) ** $CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $
07/07 15:02:18 (fd:3) (pid:1436) ** $CondorPlatform: INTEL-WINNT50 $
07/07 15:02:18 (fd:3) (pid:1436) ** PID = 1436
07/07 15:02:18 (fd:3) (pid:1436) ** Log last touched time unavailable (No such file or directory)
07/07 15:02:18 (fd:3) (pid:1436) ******************************************************
07/07 15:02:18 (fd:3) (pid:1436) Using config source: C:\condor\condor_config
07/07 15:02:18 (fd:3) (pid:1436) Using local config sources: 
07/07 15:02:18 (fd:3) (pid:1436)    C:\condor/condor_config.local
07/07 15:02:18 (fd:3) (pid:1436) Config 'LOG': no prefix ==> '$(LOCAL_DIR)/log'
07/07 15:02:18 (fd:3) (pid:1436) Config 'BIN': no prefix ==> '$(RELEASE_DIR)/bin'
07/07 15:02:18 (fd:3) (pid:1436) LISTEN <127.0.0.1:4406> fd=1796
07/07 15:02:18 (fd:3) (pid:1436) CONNECT src=<127.0.0.1:4405> fd=292 dst=<127.0.0.1:4406>
07/07 15:02:18 (fd:3) (pid:1436) ACCEPT from=<127.0.0.1:4405> newfd=1788 to=<127.0.0.1:4406>
07/07 15:02:18 (fd:3) (pid:1436) CLOSE <127.0.0.1:4406> fd=1796
07/07 15:02:18 (fd:3) (pid:1436) Setting up command socket
07/07 15:02:18 (fd:3) (pid:1436) CONDOR_INHERIT: "2064 <172.17.7.223:4398> 0 392*6*0*0*0*0***1*<172.17.7.223:0>*0*0* 368*2*0*0*0*0***0*<172.17.7.223:0>* 0"
07/07 15:02:18 (fd:3) (pid:1436) Parent PID = 2064
07/07 15:02:18 (fd:3) (pid:1436) Parent Command Sock = <172.17.7.223:4398>
07/07 15:02:18 (fd:3) (pid:1436) Inheriting Command Sockets
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> Sockets Registered
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 0: 1772 <NULL> DC Command Handler
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> Sockets Registered
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 0: 1772 <NULL> DC Command Handler
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 1: 1768 <NULL> DC Command Handler
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore: Command Socket at <172.17.7.223:4403>
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> Commands Registered
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> Commands Registered
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> Signals Registered
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 1: SIGHUP handle_dc_sighup(), Blocked:0 Pending:0
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> Signals Registered
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 1: SIGHUP handle_dc_sighup(), Blocked:0 Pending:0
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 3: SIGQUIT handle_dc_sigquit(), Blocked:0 Pending:0
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> Signals Registered
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 1: SIGHUP handle_dc_sighup(), Blocked:0 Pending:0
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 3: SIGQUIT handle_dc_sigquit(), Blocked:0 Pending:0
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 15: SIGTERM handle_dc_sigterm(), Blocked:0 Pending:0
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> Signals Registered
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 1: SIGHUP handle_dc_sighup(), Blocked:0 Pending:0
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 3: SIGQUIT handle_dc_sigquit(), Blocked:0 Pending:0
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 15: SIGTERM handle_dc_sigterm(), Blocked:0 Pending:0
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60009: DC_SERVICEWAITPIDS HandleDC_SERVICEWAITPIDS(), Blocked:0 Pending:0
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) in DaemonCore NewTimer()
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> Timers
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> ~~~~~~
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> id = 0, when = 1278514938, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) leaving DaemonCore NewTimer, id=0
07/07 15:02:18 (fd:3) (pid:1436) in DaemonCore NewTimer()
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> Timers
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> ~~~~~~
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> id = 0, when = 1278514938, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> id = 1, when = 1278514938, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) leaving DaemonCore NewTimer, id=1
07/07 15:02:18 (fd:3) (pid:1436) in DaemonCore NewTimer()
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> Timers
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> ~~~~~~
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> id = 0, when = 1278514938, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> id = 1, when = 1278514938, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> id = 2, when = 1278514938, period = 300, handler_descrip=<check_session_cache>
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) leaving DaemonCore NewTimer, id=2
07/07 15:02:18 (fd:3) (pid:1436) SEC_DEFAULT_SESSION_DURATION is undefined, using default value of 3600
07/07 15:02:18 (fd:3) (pid:1436) in DaemonCore NewTimer()
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> Timers
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> ~~~~~~
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> id = 0, when = 1278514938, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> id = 1, when = 1278514938, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> id = 2, when = 1278514938, period = 300, handler_descrip=<check_session_cache>
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> id = 3, when = 1278514938, period = 1801, handler_descrip=<handle_cookie_refresh>
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) leaving DaemonCore NewTimer, id=3
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> Commands Registered
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> Commands Registered
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> Commands Registered
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> Commands Registered
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> Commands Registered
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> Commands Registered
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> Commands Registered
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> Commands Registered
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> Commands Registered
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60015: DC_OFF_PEACEFUL handle_off_peaceful()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) 
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> Commands Registered
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:18 (fd:3) (pid:1436) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60015: DC_OFF_PEACEFUL handle_off_peaceful()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60016: DC_SET_PEACEFUL_SHUTDOWN handle_set_peaceful_shutdown()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:1436) 
07/07 15:02:19 (fd:3) (pid:1436) 
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60011: DC_NOP handle_nop()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60015: DC_OFF_PEACEFUL handle_off_peaceful()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60016: DC_SET_PEACEFUL_SHUTDOWN handle_set_peaceful_shutdown()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:1436) 
07/07 15:02:19 (fd:3) (pid:1436) 
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60011: DC_NOP handle_nop()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60013: DC_FETCH_LOG handle_fetch_log()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60015: DC_OFF_PEACEFUL handle_off_peaceful()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60016: DC_SET_PEACEFUL_SHUTDOWN handle_set_peaceful_shutdown()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:1436) 
07/07 15:02:19 (fd:3) (pid:1436) 
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60011: DC_NOP handle_nop()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60013: DC_FETCH_LOG handle_fetch_log()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60015: DC_OFF_PEACEFUL handle_off_peaceful()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60016: DC_SET_PEACEFUL_SHUTDOWN handle_set_peaceful_shutdown()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60018: DC_PURGE_LOG handle_fetch_log_history_purge()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:1436) 
07/07 15:02:19 (fd:3) (pid:1436) 
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60011: DC_NOP handle_nop()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60013: DC_FETCH_LOG handle_fetch_log()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60014: DC_INVALIDATE_KEY handle_invalidate_key()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60015: DC_OFF_PEACEFUL handle_off_peaceful()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60016: DC_SET_PEACEFUL_SHUTDOWN handle_set_peaceful_shutdown()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60018: DC_PURGE_LOG handle_fetch_log_history_purge()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:1436) 
07/07 15:02:19 (fd:3) (pid:1436) 
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60011: DC_NOP handle_nop()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60013: DC_FETCH_LOG handle_fetch_log()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60014: DC_INVALIDATE_KEY handle_invalidate_key()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60015: DC_OFF_PEACEFUL handle_off_peaceful()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60016: DC_SET_PEACEFUL_SHUTDOWN handle_set_peaceful_shutdown()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60017: DC_TIME_OFFSET time_offset_cedar_stub
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 60018: DC_PURGE_LOG handle_fetch_log_history_purge()
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:1436) 
07/07 15:02:19 (fd:3) (pid:1436) DNS_CACHE_REFRESH is undefined, using default value of 29103
07/07 15:02:19 (fd:3) (pid:1436) in DaemonCore NewTimer()
07/07 15:02:19 (fd:3) (pid:1436) 
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> Timers
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> ~~~~~~
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> id = 0, when = 1278514938, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> id = 1, when = 1278514938, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> id = 2, when = 1278514938, period = 300, handler_descrip=<check_session_cache>
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> id = 3, when = 1278514938, period = 1801, handler_descrip=<handle_cookie_refresh>
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> id = 4, when = 1278544042, period = 29103, handler_descrip=<DaemonCore::refreshDNS()>
07/07 15:02:19 (fd:3) (pid:1436) 
07/07 15:02:19 (fd:3) (pid:1436) leaving DaemonCore NewTimer, id=4
07/07 15:02:19 (fd:3) (pid:1436) PIPE_BUFFER_MAX is undefined, using default value of 10240
07/07 15:02:19 (fd:3) (pid:1436) Config 'COLLECTOR_HOST': no prefix ==> '$(CONDOR_HOST)'
07/07 15:02:19 (fd:3) (pid:1436) COLLECTOR_HOST is set to "electrabel.pylos.logilab.fr"
07/07 15:02:19 (fd:3) (pid:1436) NEGOTIATOR_TIMEOUT_MULTIPLIER is undefined, using default value of 0
07/07 15:02:19 (fd:3) (pid:1436) New Daemon obj (collector) name: "electrabel.pylos.logilab.fr", pool: "NULL", addr: "NULL"
07/07 15:02:19 (fd:3) (pid:1436) NONBLOCKING_COLLECTOR_UPDATE is undefined, using default value of True
07/07 15:02:19 (fd:3) (pid:1436) Using name "electrabel.pylos.logilab.fr" to find daemon
07/07 15:02:19 (fd:3) (pid:1436) Port not specified, using default (9618)
07/07 15:02:19 (fd:3) (pid:1436) Host info "electrabel.pylos.logilab.fr" is a hostname, finding IP address
07/07 15:02:19 (fd:3) (pid:1436) Trying to find IP addr for "electrabel.pylos.logilab.fr"
07/07 15:02:19 (fd:3) (pid:1436) Calling gethostbyname(electrabel.pylos.logilab.fr)
07/07 15:02:19 (fd:3) (pid:1436) Found IP addr in hostent: 172.17.7.223
07/07 15:02:19 (fd:3) (pid:1436) Found IP address and port <172.17.7.223:9618>
07/07 15:02:19 (fd:3) (pid:1436) DEAD_COLLECTOR_MAX_AVOIDANCE_TIME is undefined, using default value of 3600
07/07 15:02:19 (fd:3) (pid:1436) Will use UDP to update collector electrabel.pylos.logilab.fr <172.17.7.223:9618>
07/07 15:02:19 (fd:3) (pid:1436) SEC_INVALIDATE_SESSIONS_VIA_TCP is undefined, using default value of True
07/07 15:02:19 (fd:3) (pid:1436) ENABLE_SOAP is undefined, using default value of False
07/07 15:02:19 (fd:3) (pid:1436) ENABLE_WEB_SERVER is undefined, using default value of False
07/07 15:02:19 (fd:3) (pid:1436) FAKE_CREATE_THREAD is undefined, using default value of True
07/07 15:02:19 (fd:3) (pid:1436) NEGOTIATOR_NOT_RESPONDING_TIMEOUT is undefined, using default value of -1
07/07 15:02:19 (fd:3) (pid:1436) NOT_RESPONDING_TIMEOUT is undefined, using default value of 0
07/07 15:02:19 (fd:3) (pid:1436) in DaemonCore NewTimer()
07/07 15:02:19 (fd:3) (pid:1436) 
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> Timers
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> ~~~~~~
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> id = 0, when = 1278514938, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> id = 1, when = 1278514938, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> id = 2, when = 1278514938, period = 300, handler_descrip=<check_session_cache>
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> id = 3, when = 1278514938, period = 1801, handler_descrip=<handle_cookie_refresh>
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> id = 5, when = 1278514939, period = 1170, handler_descrip=<DaemonCore::SendAliveToParent>
07/07 15:02:19 (fd:3) (pid:1436) DaemonCore--> id = 4, when = 1278544042, period = 29103, handler_descrip=<DaemonCore::refreshDNS()>
07/07 15:02:19 (fd:3) (pid:1436) 
07/07 15:02:19 (fd:3) (pid:1436) leaving DaemonCore NewTimer, id=5
07/07 15:02:19 (fd:3) (pid:1436) QUILL_USE_SQL_LOG is undefined, using default value of False
07/07 15:02:19 (fd:3) (pid:1436) Config 'LOG': no prefix ==> '$(LOCAL_DIR)/log'
07/07 15:02:19 (fd:3) (pid:1436) WANT_XML_LOG is undefined, using default value of False
07/07 15:02:19 (fd:3) (pid:1436) MAX_ACCOUNTANT_DATABASE_SIZE is undefined, using default value of 1000000
07/07 15:02:19 (fd:3) (pid:1436) Config 'SPOOL': no prefix ==> '$(LOCAL_DIR)/spool'
07/07 15:02:19 (fd:3) (pid:1436) NEGOTIATOR_DISCOUNT_SUSPENDED_RESOURCES is undefined, using default value of False
07/07 15:02:19 (fd:3) (pid:1436) NEGOTIATOR_USE_SLOT_WEIGHTS is undefined, using default value of True
07/07 15:02:19 (fd:3) (pid:1436) PRIORITY_HALFLIFE=86400.000000
07/07 15:02:19 (fd:3) (pid:1436) NICE_USER_PRIO_FACTOR=10000000.000000
07/07 15:02:19 (fd:3) (pid:1436) REMOTE_PRIO_FACTOR=1.000000
07/07 15:02:19 (fd:3) (pid:1436) DEFAULT_PRIO_FACTOR=1.000000
07/07 15:02:19 (fd:3) (pid:1436) ACCOUNTANT_LOCAL_DOMAIN=
07/07 15:02:19 (fd:3) (pid:1436) MAX_ACCOUNTANT_DATABASE_SIZE=1000000
07/07 15:02:19 (fd:4) (pid:1436) About to rotate ClassAd log C:\condor/spool/Accountantnew.log
07/07 15:02:19 (fd:4) (pid:1436) Accountant::Initialize - LogFileName=C:\condor/spool/Accountantnew.log
07/07 15:02:19 (fd:4) (pid:1436) Sanity check on number of resources per user
07/07 15:02:19 (fd:4) (pid:1436) Reporting State for customer alf@pylos.logilab.fr
07/07 15:02:19 (fd:4) (pid:1436) Customer alf@pylos.logilab.fr using 0 resources
07/07 15:02:19 (fd:4) (pid:1436) Customer alf@pylos.logilab.fr using 0.000000 weighted resources
07/07 15:02:19 (fd:4) (pid:1436) (ACCOUNTANT) Updating priorities - AgingFactor=   0.999 , TimePassed=146
07/07 15:02:19 (fd:4) (pid:1436) CustomerName=Customer.alf@pylos.logilab.fr , Old Priority=0.500 , New Priority=0.499 , ResourcesUsed=0 , WeightedResourcesUsed=0.000000
07/07 15:02:19 (fd:4) (pid:1436) RecentUsage=   0.000 (unweighted    0.000), UnchargedTime=   0.000 (unweighted 0), AccumulatedUsage=7368.000 (unweighted 7368.000), BeginUsageTime=1277141103
07/07 15:02:19 (fd:4) (pid:1436) NEGOTIATOR_INTERVAL is undefined, using default value of 60
07/07 15:02:19 (fd:4) (pid:1436) NEGOTIATOR_TIMEOUT is undefined, using default value of 30
07/07 15:02:19 (fd:4) (pid:1436) NEGOTIATOR_MAX_TIME_PER_SUBMITTER is undefined, using default value of 31536000
07/07 15:02:19 (fd:4) (pid:1436) NEGOTIATOR_MAX_TIME_PER_PIESPIN is undefined, using default value of 31536000
07/07 15:02:19 (fd:4) (pid:1436) NEGOTIATOR_SOCKET_CACHE_SIZE is undefined, using default value of 16
07/07 15:02:19 (fd:4) (pid:1436) NEGOTIATOR_SOCKET_CACHE_SIZE = 16
07/07 15:02:19 (fd:4) (pid:1436) Config 'PREEMPTION_REQUIREMENTS': no prefix ==> '$(UWCS_PREEMPTION_REQUIREMENTS)'
07/07 15:02:19 (fd:4) (pid:1436) PREEMPTION_REQUIREMENTS = ( (CurrentTime - EnteredCurrentState) > (1 * (60 * 60)) && RemoteUserPrio > SubmitterUserPrio * 1.2 ) || (MY.NiceUser == True)
07/07 15:02:19 (fd:4) (pid:1436) ACCOUNTANT_HOST = None (local)
07/07 15:02:19 (fd:4) (pid:1436) NEGOTIATOR_INTERVAL = 60 sec
07/07 15:02:19 (fd:4) (pid:1436) NEGOTIATOR_TIMEOUT = 30 sec
07/07 15:02:19 (fd:4) (pid:1436) MAX_TIME_PER_SUBMITTER = 31536000 sec
07/07 15:02:19 (fd:4) (pid:1436) MAX_TIME_PER_PIESPIN = 31536000 sec
07/07 15:02:19 (fd:4) (pid:1436) Config 'PREEMPTION_RANK': no prefix ==> '$(UWCS_PREEMPTION_RANK)'
07/07 15:02:19 (fd:4) (pid:1436) PREEMPTION_RANK = (RemoteUserPrio * 1000000) - TARGET.ImageSize
07/07 15:02:19 (fd:4) (pid:1436) Config 'NEGOTIATOR_PRE_JOB_RANK': no prefix ==> '$(UWCS_NEGOTIATOR_PRE_JOB_RANK)'
07/07 15:02:19 (fd:4) (pid:1436) NEGOTIATOR_PRE_JOB_RANK = RemoteOwner =?= UNDEFINED
07/07 15:02:19 (fd:4) (pid:1436) Config 'NEGOTIATOR_POST_JOB_RANK': no prefix ==> '$(UWCS_NEGOTIATOR_POST_JOB_RANK)'
07/07 15:02:19 (fd:4) (pid:1436) NEGOTIATOR_POST_JOB_RANK = (RemoteOwner =?= UNDEFINED) * (KFlops - SlotID - 1.0e10*(Offline=?=True))
07/07 15:02:19 (fd:4) (pid:1436) NEGOTIATOR_UPDATE_INTERVAL is undefined, using default value of 300
07/07 15:02:19 (fd:4) (pid:1436) NEGOTIATOR_MATCHLIST_CACHING is undefined, using default value of True
07/07 15:02:19 (fd:4) (pid:1436) NEGOTIATOR_CONSIDER_PREEMPTION is undefined, using default value of True
07/07 15:02:19 (fd:4) (pid:1436) NEGOTIATOR_INFORM_STARTD is undefined, using default value of True
07/07 15:02:19 (fd:4) (pid:1436) NEGOTIATOR_USE_NONBLOCKING_STARTD_CONTACT is undefined, using default value of True
07/07 15:02:19 (fd:4) (pid:1436) PREEMPTION_REQUIREMENTS_STABLE is undefined, using default value of True
07/07 15:02:19 (fd:4) (pid:1436) PREEMPTION_RANK_STABLE is undefined, using default value of True
07/07 15:02:19 (fd:4) (pid:1436) 
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60011: DC_NOP handle_nop()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60013: DC_FETCH_LOG handle_fetch_log()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60014: DC_INVALIDATE_KEY handle_invalidate_key()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60015: DC_OFF_PEACEFUL handle_off_peaceful()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60016: DC_SET_PEACEFUL_SHUTDOWN handle_set_peaceful_shutdown()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60017: DC_TIME_OFFSET time_offset_cedar_stub
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60018: DC_PURGE_LOG handle_fetch_log_history_purge()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 421: Reschedule RESCHEDULE_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:4) (pid:1436) 
07/07 15:02:19 (fd:4) (pid:1436) 
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60011: DC_NOP handle_nop()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60013: DC_FETCH_LOG handle_fetch_log()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60014: DC_INVALIDATE_KEY handle_invalidate_key()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60015: DC_OFF_PEACEFUL handle_off_peaceful()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60016: DC_SET_PEACEFUL_SHUTDOWN handle_set_peaceful_shutdown()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60017: DC_TIME_OFFSET time_offset_cedar_stub
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60018: DC_PURGE_LOG handle_fetch_log_history_purge()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 421: Reschedule RESCHEDULE_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 460: ResetAllUsage RESET_ALL_USAGE_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) 
07/07 15:02:19 (fd:4) (pid:1436) 
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60011: DC_NOP handle_nop()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60013: DC_FETCH_LOG handle_fetch_log()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60014: DC_INVALIDATE_KEY handle_invalidate_key()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60015: DC_OFF_PEACEFUL handle_off_peaceful()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60016: DC_SET_PEACEFUL_SHUTDOWN handle_set_peaceful_shutdown()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60017: DC_TIME_OFFSET time_offset_cedar_stub
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60018: DC_PURGE_LOG handle_fetch_log_history_purge()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 421: Reschedule RESCHEDULE_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 458: ResetUsage RESET_USAGE_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 460: ResetAllUsage RESET_ALL_USAGE_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) 
07/07 15:02:19 (fd:4) (pid:1436) 
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60011: DC_NOP handle_nop()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60013: DC_FETCH_LOG handle_fetch_log()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60014: DC_INVALIDATE_KEY handle_invalidate_key()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60015: DC_OFF_PEACEFUL handle_off_peaceful()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60016: DC_SET_PEACEFUL_SHUTDOWN handle_set_peaceful_shutdown()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60017: DC_TIME_OFFSET time_offset_cedar_stub
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60018: DC_PURGE_LOG handle_fetch_log_history_purge()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 421: Reschedule RESCHEDULE_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 458: ResetUsage RESET_USAGE_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 460: ResetAllUsage RESET_ALL_USAGE_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 482: DeleteUser DELETE_USER_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) 
07/07 15:02:19 (fd:4) (pid:1436) 
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60011: DC_NOP handle_nop()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60013: DC_FETCH_LOG handle_fetch_log()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60014: DC_INVALIDATE_KEY handle_invalidate_key()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60015: DC_OFF_PEACEFUL handle_off_peaceful()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60016: DC_SET_PEACEFUL_SHUTDOWN handle_set_peaceful_shutdown()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60017: DC_TIME_OFFSET time_offset_cedar_stub
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60018: DC_PURGE_LOG handle_fetch_log_history_purge()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 421: Reschedule RESCHEDULE_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 458: ResetUsage RESET_USAGE_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 459: SetPriorityFactor SET_PRIORITYFACTOR_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 460: ResetAllUsage RESET_ALL_USAGE_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 482: DeleteUser DELETE_USER_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) 
07/07 15:02:19 (fd:4) (pid:1436) 
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60011: DC_NOP handle_nop()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60013: DC_FETCH_LOG handle_fetch_log()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60014: DC_INVALIDATE_KEY handle_invalidate_key()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60015: DC_OFF_PEACEFUL handle_off_peaceful()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60016: DC_SET_PEACEFUL_SHUTDOWN handle_set_peaceful_shutdown()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60017: DC_TIME_OFFSET time_offset_cedar_stub
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60018: DC_PURGE_LOG handle_fetch_log_history_purge()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 421: Reschedule RESCHEDULE_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 449: SetPriority SET_PRIORITY_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 458: ResetUsage RESET_USAGE_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 459: SetPriorityFactor SET_PRIORITYFACTOR_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 460: ResetAllUsage RESET_ALL_USAGE_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 482: DeleteUser DELETE_USER_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) 
07/07 15:02:19 (fd:4) (pid:1436) 
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60011: DC_NOP handle_nop()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60013: DC_FETCH_LOG handle_fetch_log()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60014: DC_INVALIDATE_KEY handle_invalidate_key()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60015: DC_OFF_PEACEFUL handle_off_peaceful()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60016: DC_SET_PEACEFUL_SHUTDOWN handle_set_peaceful_shutdown()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60017: DC_TIME_OFFSET time_offset_cedar_stub
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60018: DC_PURGE_LOG handle_fetch_log_history_purge()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 421: Reschedule RESCHEDULE_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 449: SetPriority SET_PRIORITY_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 458: ResetUsage RESET_USAGE_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 459: SetPriorityFactor SET_PRIORITYFACTOR_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 460: ResetAllUsage RESET_ALL_USAGE_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 482: DeleteUser DELETE_USER_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 494: SetAccumUsage SET_ACCUMUSAGE_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) 
07/07 15:02:19 (fd:4) (pid:1436) 
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60011: DC_NOP handle_nop()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60013: DC_FETCH_LOG handle_fetch_log()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60014: DC_INVALIDATE_KEY handle_invalidate_key()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60015: DC_OFF_PEACEFUL handle_off_peaceful()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60016: DC_SET_PEACEFUL_SHUTDOWN handle_set_peaceful_shutdown()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60017: DC_TIME_OFFSET time_offset_cedar_stub
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60018: DC_PURGE_LOG handle_fetch_log_history_purge()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 421: Reschedule RESCHEDULE_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 449: SetPriority SET_PRIORITY_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 458: ResetUsage RESET_USAGE_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 459: SetPriorityFactor SET_PRIORITYFACTOR_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 460: ResetAllUsage RESET_ALL_USAGE_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 482: DeleteUser DELETE_USER_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 494: SetAccumUsage SET_ACCUMUSAGE_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 495: SetBeginUsageTime SET_BEGINTIME_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) 
07/07 15:02:19 (fd:4) (pid:1436) 
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60011: DC_NOP handle_nop()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60013: DC_FETCH_LOG handle_fetch_log()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60014: DC_INVALIDATE_KEY handle_invalidate_key()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60015: DC_OFF_PEACEFUL handle_off_peaceful()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60016: DC_SET_PEACEFUL_SHUTDOWN handle_set_peaceful_shutdown()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60017: DC_TIME_OFFSET time_offset_cedar_stub
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60018: DC_PURGE_LOG handle_fetch_log_history_purge()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 421: Reschedule RESCHEDULE_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 449: SetPriority SET_PRIORITY_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 458: ResetUsage RESET_USAGE_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 459: SetPriorityFactor SET_PRIORITYFACTOR_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 460: ResetAllUsage RESET_ALL_USAGE_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 482: DeleteUser DELETE_USER_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 494: SetAccumUsage SET_ACCUMUSAGE_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 495: SetBeginUsageTime SET_BEGINTIME_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 496: SetLastUsageTime SET_LASTTIME_commandHandler
07/07 15:02:19 (fd:4) (pid:1436) 
07/07 15:02:19 (fd:4) (pid:1436) 
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60011: DC_NOP handle_nop()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60013: DC_FETCH_LOG handle_fetch_log()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60014: DC_INVALIDATE_KEY handle_invalidate_key()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60015: DC_OFF_PEACEFUL handle_off_peaceful()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60016: DC_SET_PEACEFUL_SHUTDOWN handle_set_peaceful_shutdown()
07/07 15:02:19 (fd:4) (pid:1436) DaemonCore--> 60017: DC_TIME_OFFSET time_offset_cedar_stub
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 60018: DC_PURGE_LOG handle_fetch_log_history_purge()
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 421: Reschedule RESCHEDULE_commandHandler
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 449: SetPriority SET_PRIORITY_commandHandler
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 451: GetPriority GET_PRIORITY_commandHandler
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 458: ResetUsage RESET_USAGE_commandHandler
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 459: SetPriorityFactor SET_PRIORITYFACTOR_commandHandler
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 460: ResetAllUsage RESET_ALL_USAGE_commandHandler
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 482: DeleteUser DELETE_USER_commandHandler
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 494: SetAccumUsage SET_ACCUMUSAGE_commandHandler
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 495: SetBeginUsageTime SET_BEGINTIME_commandHandler
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 496: SetLastUsageTime SET_LASTTIME_commandHandler
07/07 15:02:20 (fd:4) (pid:1436) 
07/07 15:02:20 (fd:4) (pid:1436) 
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> Commands Registered
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 60011: DC_NOP handle_nop()
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 60013: DC_FETCH_LOG handle_fetch_log()
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 60014: DC_INVALIDATE_KEY handle_invalidate_key()
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 60015: DC_OFF_PEACEFUL handle_off_peaceful()
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 60016: DC_SET_PEACEFUL_SHUTDOWN handle_set_peaceful_shutdown()
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 60017: DC_TIME_OFFSET time_offset_cedar_stub
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 60018: DC_PURGE_LOG handle_fetch_log_history_purge()
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 421: Reschedule RESCHEDULE_commandHandler
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 449: SetPriority SET_PRIORITY_commandHandler
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 451: GetPriority GET_PRIORITY_commandHandler
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 458: ResetUsage RESET_USAGE_commandHandler
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 459: SetPriorityFactor SET_PRIORITYFACTOR_commandHandler
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 460: ResetAllUsage RESET_ALL_USAGE_commandHandler
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 463: GetResList GET_RESLIST_commandHandler
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 482: DeleteUser DELETE_USER_commandHandler
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 494: SetAccumUsage SET_ACCUMUSAGE_commandHandler
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 495: SetBeginUsageTime SET_BEGINTIME_commandHandler
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> 496: SetLastUsageTime SET_LASTTIME_commandHandler
07/07 15:02:20 (fd:4) (pid:1436) 
07/07 15:02:20 (fd:4) (pid:1436) in DaemonCore NewTimer()
07/07 15:02:20 (fd:4) (pid:1436) 
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> Timers
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> ~~~~~~
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 0, when = 1278514938, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 1, when = 1278514938, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 2, when = 1278514938, period = 300, handler_descrip=<check_session_cache>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 3, when = 1278514938, period = 1801, handler_descrip=<handle_cookie_refresh>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 5, when = 1278514939, period = 1170, handler_descrip=<DaemonCore::SendAliveToParent>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 6, when = 1278514940, period = 60, handler_descrip=<Time to negotiate>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 4, when = 1278544042, period = 29103, handler_descrip=<DaemonCore::refreshDNS()>
07/07 15:02:20 (fd:4) (pid:1436) 
07/07 15:02:20 (fd:4) (pid:1436) leaving DaemonCore NewTimer, id=6
07/07 15:02:20 (fd:4) (pid:1436) in DaemonCore NewTimer()
07/07 15:02:20 (fd:4) (pid:1436) 
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> Timers
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> ~~~~~~
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 0, when = 1278514938, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 1, when = 1278514938, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 2, when = 1278514938, period = 300, handler_descrip=<check_session_cache>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 3, when = 1278514938, period = 1801, handler_descrip=<handle_cookie_refresh>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 5, when = 1278514939, period = 1170, handler_descrip=<DaemonCore::SendAliveToParent>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 6, when = 1278514940, period = 60, handler_descrip=<Time to negotiate>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 7, when = 1278514940, period = 300, handler_descrip=<Update Collector>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 4, when = 1278544042, period = 29103, handler_descrip=<DaemonCore::refreshDNS()>
07/07 15:02:20 (fd:4) (pid:1436) 
07/07 15:02:20 (fd:4) (pid:1436) leaving DaemonCore NewTimer, id=7
07/07 15:02:20 (fd:4) (pid:1436) selector 0012FA70 resetting
07/07 15:02:20 (fd:4) (pid:1436) ENABLE_STDOUT_TESTING is undefined, using default value of False
07/07 15:02:20 (fd:4) (pid:1436) In DaemonCore Timeout()
07/07 15:02:20 (fd:4) (pid:1436) 
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> Timers
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> ~~~~~~
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 0, when = 1278514938, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 1, when = 1278514938, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 2, when = 1278514938, period = 300, handler_descrip=<check_session_cache>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 3, when = 1278514938, period = 1801, handler_descrip=<handle_cookie_refresh>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 5, when = 1278514939, period = 1170, handler_descrip=<DaemonCore::SendAliveToParent>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 6, when = 1278514940, period = 60, handler_descrip=<Time to negotiate>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 7, when = 1278514940, period = 300, handler_descrip=<Update Collector>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 4, when = 1278544042, period = 29103, handler_descrip=<DaemonCore::refreshDNS()>
07/07 15:02:20 (fd:4) (pid:1436) 
07/07 15:02:20 (fd:4) (pid:1436) Calling Timer handler 0 (dc_touch_log_file)
07/07 15:02:20 (fd:4) (pid:1436) TOUCH_LOG_INTERVAL is undefined, using default value of 60
07/07 15:02:20 (fd:4) (pid:1436) in DaemonCore NewTimer()
07/07 15:02:20 (fd:4) (pid:1436) 
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> Timers
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> ~~~~~~
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 0, when = 1278514938, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 1, when = 1278514938, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 2, when = 1278514938, period = 300, handler_descrip=<check_session_cache>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 3, when = 1278514938, period = 1801, handler_descrip=<handle_cookie_refresh>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 5, when = 1278514939, period = 1170, handler_descrip=<DaemonCore::SendAliveToParent>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 6, when = 1278514940, period = 60, handler_descrip=<Time to negotiate>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 7, when = 1278514940, period = 300, handler_descrip=<Update Collector>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 8, when = 1278515000, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 4, when = 1278544042, period = 29103, handler_descrip=<DaemonCore::refreshDNS()>
07/07 15:02:20 (fd:4) (pid:1436) 
07/07 15:02:20 (fd:4) (pid:1436) leaving DaemonCore NewTimer, id=8
07/07 15:02:20 (fd:4) (pid:1436) Return from Timer handler 0 (dc_touch_log_file)
07/07 15:02:20 (fd:4) (pid:1436) PRIV_CONDOR --> PRIV_CONDOR at ..\src\condor_daemon_core.V6\daemon_core.cpp:3548
07/07 15:02:20 (fd:4) (pid:1436) Calling Timer handler 1 (dc_touch_lock_files)
07/07 15:02:20 (fd:4) (pid:1436) PRIV_CONDOR --> PRIV_CONDOR at ..\src\condor_daemon_core.V6\daemon_core_main.cpp:549
07/07 15:02:20 (fd:4) (pid:1436) PRIV_CONDOR --> PRIV_CONDOR at ..\src\condor_daemon_core.V6\daemon_core_main.cpp:553
07/07 15:02:20 (fd:4) (pid:1436) LOCK_FILE_UPDATE_INTERVAL is undefined, using default value of 28800
07/07 15:02:20 (fd:4) (pid:1436) in DaemonCore NewTimer()
07/07 15:02:20 (fd:4) (pid:1436) 
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> Timers
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> ~~~~~~
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 1, when = 1278514938, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 2, when = 1278514938, period = 300, handler_descrip=<check_session_cache>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 3, when = 1278514938, period = 1801, handler_descrip=<handle_cookie_refresh>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 5, when = 1278514939, period = 1170, handler_descrip=<DaemonCore::SendAliveToParent>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 6, when = 1278514940, period = 60, handler_descrip=<Time to negotiate>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 7, when = 1278514940, period = 300, handler_descrip=<Update Collector>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 8, when = 1278515000, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 9, when = 1278543740, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 4, when = 1278544042, period = 29103, handler_descrip=<DaemonCore::refreshDNS()>
07/07 15:02:20 (fd:4) (pid:1436) 
07/07 15:02:20 (fd:4) (pid:1436) leaving DaemonCore NewTimer, id=9
07/07 15:02:20 (fd:4) (pid:1436) Return from Timer handler 1 (dc_touch_lock_files)
07/07 15:02:20 (fd:4) (pid:1436) PRIV_CONDOR --> PRIV_CONDOR at ..\src\condor_daemon_core.V6\daemon_core.cpp:3548
07/07 15:02:20 (fd:4) (pid:1436) Calling Timer handler 2 (check_session_cache)
07/07 15:02:20 (fd:4) (pid:1436) Return from Timer handler 2 (check_session_cache)
07/07 15:02:20 (fd:4) (pid:1436) PRIV_CONDOR --> PRIV_CONDOR at ..\src\condor_daemon_core.V6\daemon_core.cpp:3548
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore Timeout() Complete, returning 0 
07/07 15:02:20 (fd:4) (pid:1436) selector 0012FA70 resetting
07/07 15:02:20 (fd:4) (pid:1436) selector 0012FA70 adding fd 1772 ()
07/07 15:02:20 (fd:4) (pid:1436) selector 0012FA70 adding fd 1768 ()
07/07 15:02:20 (fd:4) (pid:1436) selector 0012FA70 adding fd 1788 ()
07/07 15:02:20 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:20 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:20 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:20 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:20 (fd:4) (pid:1436) In DaemonCore Timeout()
07/07 15:02:20 (fd:4) (pid:1436) 
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> Timers
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> ~~~~~~
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 3, when = 1278514938, period = 1801, handler_descrip=<handle_cookie_refresh>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 5, when = 1278514939, period = 1170, handler_descrip=<DaemonCore::SendAliveToParent>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 6, when = 1278514940, period = 60, handler_descrip=<Time to negotiate>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 7, when = 1278514940, period = 300, handler_descrip=<Update Collector>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 8, when = 1278515000, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 2, when = 1278515240, period = 300, handler_descrip=<check_session_cache>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 9, when = 1278543740, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore--> id = 4, when = 1278544042, period = 29103, handler_descrip=<DaemonCore::refreshDNS()>
07/07 15:02:20 (fd:4) (pid:1436) 
07/07 15:02:20 (fd:4) (pid:1436) Calling Timer handler 3 (handle_cookie_refresh)
07/07 15:02:20 (fd:4) (pid:1436) Return from Timer handler 3 (handle_cookie_refresh)
07/07 15:02:20 (fd:4) (pid:1436) PRIV_CONDOR --> PRIV_CONDOR at ..\src\condor_daemon_core.V6\daemon_core.cpp:3548
07/07 15:02:20 (fd:4) (pid:1436) Calling Timer handler 5 (DaemonCore::SendAliveToParent)
07/07 15:02:20 (fd:4) (pid:1436) DaemonCore: in SendAliveToParent()
07/07 15:02:20 (fd:4) (pid:1436) NEGOTIATOR_TIMEOUT_MULTIPLIER is undefined, using default value of 0
07/07 15:02:20 (fd:4) (pid:1436) New Daemon obj (any) name: "NULL", pool: "NULL", addr: "<172.17.7.223:4398>"
07/07 15:02:20 (fd:4) (pid:1436) SECMAN: command 60008 DC_CHILDALIVE to <172.17.7.223:4398> from UDP port 4414 (blocking).
07/07 15:02:20 (fd:4) (pid:1436) SECMAN: no cached key for {<172.17.7.223:4398>,<60008>}.
07/07 15:02:20 (fd:4) (pid:1436) SECMAN: Security Policy:
MyType = ""
TargetType = ""
AuthMethods = "NTSSPI,KERBEROS"
CryptoMethods = "3DES,BLOWFISH"
OutgoingNegotiation = "PREFERRED"
Authentication = "OPTIONAL"
Encryption = "OPTIONAL"
Integrity = "OPTIONAL"
Enact = "NO"
Subsystem = "NEGOTIATOR"
ParentUniqueID = "electrabel:2064:1278514935"
ServerPid = 1436
SessionDuration = "86400"
07/07 15:02:20 (fd:4) (pid:1436) SECMAN: negotiating security for command 60008.
07/07 15:02:20 (fd:4) (pid:1436) SECMAN: need to start a session via TCP
07/07 15:02:20 (fd:4) (pid:1436) SEC_TCP_SESSION_TIMEOUT is undefined, using default value of 20
07/07 15:02:20 (fd:4) (pid:1436) selector 0012082C resetting
07/07 15:02:20 (fd:4) (pid:1436) selector 0012082C resetting
07/07 15:02:20 (fd:4) (pid:1436) selector 0012082C adding fd 1740 ()
07/07 15:02:20 (fd:4) (pid:1436) selector 0012082C adding fd 1740 ()
07/07 15:02:20 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:20 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:20 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:20 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:20 (fd:4) (pid:1436) CONNECT src=<172.17.7.223:4415> fd=1740 dst=<172.17.7.223:4398>
07/07 15:02:20 (fd:4) (pid:1436) SECMAN: command 60010 DC_CHILDALIVE to <172.17.7.223:4398> from TCP port 4415 (blocking).
07/07 15:02:20 (fd:4) (pid:1436) SECMAN: no cached key for {<172.17.7.223:4398>,<60010>}.
07/07 15:02:20 (fd:4) (pid:1436) SECMAN: Security Policy:
MyType = ""
TargetType = ""
AuthMethods = "NTSSPI,KERBEROS"
CryptoMethods = "3DES,BLOWFISH"
OutgoingNegotiation = "PREFERRED"
Authentication = "OPTIONAL"
Encryption = "OPTIONAL"
Integrity = "OPTIONAL"
Enact = "NO"
Subsystem = "NEGOTIATOR"
ParentUniqueID = "electrabel:2064:1278514935"
ServerPid = 1436
SessionDuration = "86400"
NewSession = "YES"
07/07 15:02:20 (fd:4) (pid:1436) SECMAN: negotiating security for command 60010.
07/07 15:02:20 (fd:4) (pid:1436) SECMAN: sending DC_AUTHENTICATE command
07/07 15:02:20 (fd:4) (pid:1436) SECMAN: sending following classad:
MyType = ""
TargetType = ""
AuthMethods = "NTSSPI,KERBEROS"
CryptoMethods = "3DES,BLOWFISH"
OutgoingNegotiation = "PREFERRED"
Authentication = "OPTIONAL"
Encryption = "OPTIONAL"
Integrity = "OPTIONAL"
Enact = "NO"
Subsystem = "NEGOTIATOR"
ParentUniqueID = "electrabel:2064:1278514935"
ServerPid = 1436
SessionDuration = "86400"
NewSession = "YES"
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
ServerCommandSock = "<172.17.7.223:4403>"
Command = 60010
AuthCommand = 60008
07/07 15:02:20 (fd:4) (pid:1436) selector 00120588 resetting
07/07 15:02:20 (fd:4) (pid:1436) condor_write(fd=1740 <172.17.7.223:4398>,,size=518,timeout=20,flags=0)
07/07 15:02:20 (fd:4) (pid:1436) selector 00120588 adding fd 1740 ()
07/07 15:02:20 (fd:4) (pid:1436) selector 00120588 adding fd 1740 ()
07/07 15:02:20 (fd:4) (pid:1436) selector 00120588 adding fd 1740 ()
07/07 15:02:20 (fd:4) (pid:1436) selector 00120588 adding fd 1740 ()
07/07 15:02:20 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:20 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:20 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:20 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:20 (fd:4) (pid:1436) Entering thread safe start [send] in condor_rw.cpp:357 2364()
07/07 15:02:20 (fd:4) (pid:1436) Leaving thread safe start [send] in condor_rw.cpp:357 2364()
07/07 15:02:20 (fd:4) (pid:1436) Entering thread safe stop [send] in condor_rw.cpp:359 2364()
07/07 15:02:20 (fd:4) (pid:1436) Leaving thread safe stop [send] in condor_rw.cpp:359 2364()
07/07 15:02:20 (fd:4) (pid:1436) selector 0012060C resetting
07/07 15:02:20 (fd:4) (pid:1436) condor_read(fd=1740 <172.17.7.223:4398>,,size=5,timeout=20,flags=0)
07/07 15:02:20 (fd:4) (pid:1436) selector 0012060C adding fd 1740 ()
07/07 15:02:20 (fd:4) (pid:1436) condor_read(): fd=1740
07/07 15:02:20 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:20 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:21 (fd:4) (pid:1436) condor_read(): select returned 1
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:21 (fd:4) (pid:1436) selector 001205F0 resetting
07/07 15:02:21 (fd:4) (pid:1436) condor_read(fd=1740 <172.17.7.223:4398>,,size=296,timeout=20,flags=0)
07/07 15:02:21 (fd:4) (pid:1436) selector 001205F0 adding fd 1740 ()
07/07 15:02:21 (fd:4) (pid:1436) condor_read(): fd=1740
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:21 (fd:4) (pid:1436) condor_read(): select returned 1
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: server responded with:
MyType = "(unknown type)"
TargetType = "(unknown type)"
Authentication = "NO"
Encryption = "NO"
Integrity = "NO"
AuthMethodsList = "NTSSPI,KERBEROS"
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
SessionDuration = "86400"
Enact = "YES"
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
07/07 15:02:21 (fd:4) (pid:1436) selector 001205D8 resetting
07/07 15:02:21 (fd:4) (pid:1436) condor_read(fd=1740 <172.17.7.223:4398>,,size=5,timeout=20,flags=0)
07/07 15:02:21 (fd:4) (pid:1436) selector 001205D8 adding fd 1740 ()
07/07 15:02:21 (fd:4) (pid:1436) condor_read(): fd=1740
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:21 (fd:4) (pid:1436) condor_read(): select returned 1
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:21 (fd:4) (pid:1436) selector 001205BC resetting
07/07 15:02:21 (fd:4) (pid:1436) condor_read(fd=1740 <172.17.7.223:4398>,,size=127,timeout=20,flags=0)
07/07 15:02:21 (fd:4) (pid:1436) selector 001205BC adding fd 1740 ()
07/07 15:02:21 (fd:4) (pid:1436) condor_read(): fd=1740
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:21 (fd:4) (pid:1436) condor_read(): select returned 1
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: received post-auth classad:
MyType = "(unknown type)"
TargetType = "(unknown type)"
Sid = "electrabel:2064:1278514941:2"
ValidCommands = "60000,60008,60017,60004,60007,457"
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: policy to be cached:
MyType = ""
TargetType = ""
OutgoingNegotiation = "PREFERRED"
Subsystem = "NEGOTIATOR"
Command = 60010
AuthCommand = 60008
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
Enact = "YES"
AuthMethodsList = "NTSSPI,KERBEROS"
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
Authentication = "NO"
Encryption = "NO"
Integrity = "NO"
SessionDuration = "86400"
UseSession = "YES"
Sid = "electrabel:2064:1278514941:2"
ValidCommands = "60000,60008,60017,60004,60007,457"
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: added session electrabel:2064:1278514941:2 to cache for 86400 seconds.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:4398>,<60000>} mapped to session electrabel:2064:1278514941:2.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:4398>,<60008>} mapped to session electrabel:2064:1278514941:2.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:4398>,<60017>} mapped to session electrabel:2064:1278514941:2.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:4398>,<60004>} mapped to session electrabel:2064:1278514941:2.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:4398>,<60007>} mapped to session electrabel:2064:1278514941:2.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:4398>,<457>} mapped to session electrabel:2064:1278514941:2.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: startCommand succeeded.
07/07 15:02:21 (fd:4) (pid:1436) Authorizing server '*/172.17.7.223'.
07/07 15:02:21 (fd:4) (pid:1436) Config 'ALLOW_READ': no prefix ==> '*/electrabel.pylos.logilab.fr, */electrabel2.pylos.logilab.fr'
07/07 15:02:21 (fd:4) (pid:1436) IPVERIFY: allow READ: */electrabel.pylos.logilab.fr, */electrabel2.pylos.logilab.fr (from config value ALLOW_READ)
07/07 15:02:21 (fd:4) (pid:1436) Config 'ALLOW_WRITE': no prefix ==> '*/electrabel.pylos.logilab.fr, */electrabel2.pylos.logilab.fr'
07/07 15:02:21 (fd:4) (pid:1436) IPVERIFY: allow WRITE: */electrabel.pylos.logilab.fr, */electrabel2.pylos.logilab.fr (from config value ALLOW_WRITE)
07/07 15:02:21 (fd:4) (pid:1436) Config 'ALLOW_NEGOTIATOR': no prefix ==> '$(CONDOR_HOST)'
07/07 15:02:21 (fd:4) (pid:1436) IPVERIFY: allow NEGOTIATOR: electrabel.pylos.logilab.fr (from config value ALLOW_NEGOTIATOR)
07/07 15:02:21 (fd:4) (pid:1436) Config 'ALLOW_ADMINISTRATOR': no prefix ==> '*/electrabel.pylos.logilab.fr, */electrabel2.pylos.logilab.fr'
07/07 15:02:21 (fd:4) (pid:1436) IPVERIFY: allow ADMINISTRATOR: */electrabel.pylos.logilab.fr, */electrabel2.pylos.logilab.fr (from config value ALLOW_ADMINISTRATOR)
07/07 15:02:21 (fd:4) (pid:1436) Config 'ALLOW_OWNER': no prefix ==> '$(FULL_HOSTNAME), $(ALLOW_ADMINISTRATOR)'
07/07 15:02:21 (fd:4) (pid:1436) IPVERIFY: allow OWNER: electrabel.pylos.logilab.fr, */electrabel.pylos.logilab.fr, */electrabel2.pylos.logilab.fr (from config value ALLOW_OWNER)
07/07 15:02:21 (fd:4) (pid:1436) Config 'ALLOW_CONFIG': no prefix ==> '*/electrabel.pylos.logilab.fr, */electrabel2.pylos.logilab.fr'
07/07 15:02:21 (fd:4) (pid:1436) IPVERIFY: allow CONFIG: */electrabel.pylos.logilab.fr, */electrabel2.pylos.logilab.fr (from config value ALLOW_CONFIG)
07/07 15:02:21 (fd:4) (pid:1436) Config 'ALLOW_WRITE': no prefix ==> '*/electrabel.pylos.logilab.fr, */electrabel2.pylos.logilab.fr'
07/07 15:02:21 (fd:4) (pid:1436) IPVERIFY: allow DAEMON: */electrabel.pylos.logilab.fr, */electrabel2.pylos.logilab.fr (from config value ALLOW_WRITE)
07/07 15:02:21 (fd:4) (pid:1436) ipverify: SOAP optimized to allow anyone
07/07 15:02:21 (fd:4) (pid:1436) ipverify: DEFAULT optimized to allow anyone
07/07 15:02:21 (fd:4) (pid:1436) ipverify: CLIENT optimized to allow anyone
07/07 15:02:21 (fd:4) (pid:1436) Config 'ALLOW_WRITE': no prefix ==> '*/electrabel.pylos.logilab.fr, */electrabel2.pylos.logilab.fr'
07/07 15:02:21 (fd:4) (pid:1436) IPVERIFY: allow ADVERTISE_STARTD: */electrabel.pylos.logilab.fr, */electrabel2.pylos.logilab.fr (from config value ALLOW_WRITE)
07/07 15:02:21 (fd:4) (pid:1436) Config 'ALLOW_WRITE': no prefix ==> '*/electrabel.pylos.logilab.fr, */electrabel2.pylos.logilab.fr'
07/07 15:02:21 (fd:4) (pid:1436) IPVERIFY: allow ADVERTISE_SCHEDD: */electrabel.pylos.logilab.fr, */electrabel2.pylos.logilab.fr (from config value ALLOW_WRITE)
07/07 15:02:21 (fd:4) (pid:1436) Config 'ALLOW_WRITE': no prefix ==> '*/electrabel.pylos.logilab.fr, */electrabel2.pylos.logilab.fr'
07/07 15:02:21 (fd:4) (pid:1436) IPVERIFY: allow ADVERTISE_MASTER: */electrabel.pylos.logilab.fr, */electrabel2.pylos.logilab.fr (from config value ALLOW_WRITE)
07/07 15:02:21 (fd:4) (pid:1436) Initialized the following authorization table:
07/07 15:02:21 (fd:4) (pid:1436) Authorizations yet to be resolved:
07/07 15:02:21 (fd:4) (pid:1436) allow READ:  */172.17.7.223 */172.17.7.224 */electrabel2.pylos.logilab.fr */electrabel.pylos.logilab.fr
07/07 15:02:21 (fd:4) (pid:1436) allow WRITE:  */172.17.7.223 */172.17.7.224 */electrabel2.pylos.logilab.fr */electrabel.pylos.logilab.fr
07/07 15:02:21 (fd:4) (pid:1436) allow NEGOTIATOR:  */172.17.7.223 */electrabel.pylos.logilab.fr
07/07 15:02:21 (fd:4) (pid:1436) allow ADMINISTRATOR:  */172.17.7.223 */172.17.7.224 */electrabel2.pylos.logilab.fr */electrabel.pylos.logilab.fr
07/07 15:02:21 (fd:4) (pid:1436) allow OWNER:  */172.17.7.223 */172.17.7.223 */172.17.7.224 */electrabel2.pylos.logilab.fr */electrabel.pylos.logilab.fr */electrabel.pylos.logilab.fr
07/07 15:02:21 (fd:4) (pid:1436) allow CONFIG:  */172.17.7.223 */172.17.7.224 */electrabel2.pylos.logilab.fr */electrabel.pylos.logilab.fr
07/07 15:02:21 (fd:4) (pid:1436) allow DAEMON:  */172.17.7.223 */172.17.7.224 */electrabel2.pylos.logilab.fr */electrabel.pylos.logilab.fr
07/07 15:02:21 (fd:4) (pid:1436) allow ADVERTISE_STARTD:  */172.17.7.223 */172.17.7.224 */electrabel2.pylos.logilab.fr */electrabel.pylos.logilab.fr
07/07 15:02:21 (fd:4) (pid:1436) allow ADVERTISE_SCHEDD:  */172.17.7.223 */172.17.7.224 */electrabel2.pylos.logilab.fr */electrabel.pylos.logilab.fr
07/07 15:02:21 (fd:4) (pid:1436) allow ADVERTISE_MASTER:  */172.17.7.223 */172.17.7.224 */electrabel2.pylos.logilab.fr */electrabel.pylos.logilab.fr
07/07 15:02:21 (fd:4) (pid:1436) CLOSE <172.17.7.223:4415> fd=1740
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: succesfully created security session to <172.17.7.223:4398> via TCP!
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: resuming command 60008 DC_CHILDALIVE to <172.17.7.223:4398> from UDP port 4414 (blocking).
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: using session electrabel:2064:1278514941:2 for {<172.17.7.223:4398>,<60008>}.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: found cached session id electrabel:2064:1278514941:2 for {<172.17.7.223:4398>,<60008>}.
07/07 15:02:21 (fd:4) (pid:1436) SEC_DEBUG_PRINT_KEYS is undefined, using default value of False
MyType = ""
TargetType = ""
ParentUniqueID = "electrabel:2064:1278514935"
ServerPid = 1436
OutgoingNegotiation = "PREFERRED"
Subsystem = "NEGOTIATOR"
Command = 60010
AuthCommand = 60008
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
Enact = "YES"
AuthMethodsList = "NTSSPI,KERBEROS"
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
Authentication = "NO"
Encryption = "NO"
Integrity = "NO"
SessionDuration = "86400"
UseSession = "YES"
Sid = "electrabel:2064:1278514941:2"
ValidCommands = "60000,60008,60017,60004,60007,457"
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: Security Policy:
MyType = ""
TargetType = ""
ParentUniqueID = "electrabel:2064:1278514935"
ServerPid = 1436
OutgoingNegotiation = "PREFERRED"
Subsystem = "NEGOTIATOR"
Command = 60010
AuthCommand = 60008
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
Enact = "YES"
AuthMethodsList = "NTSSPI,KERBEROS"
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
Authentication = "NO"
Encryption = "NO"
Integrity = "NO"
SessionDuration = "86400"
UseSession = "YES"
Sid = "electrabel:2064:1278514941:2"
ValidCommands = "60000,60008,60017,60004,60007,457"
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: negotiating security for command 60008.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: UDP, m_have_session == 1, peer_can_negotiate == 1
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: UDP has session electrabel:2064:1278514941:2.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: sending DC_AUTHENTICATE command
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: sending following classad:
MyType = ""
TargetType = ""
ParentUniqueID = "electrabel:2064:1278514935"
ServerPid = 1436
OutgoingNegotiation = "PREFERRED"
Subsystem = "NEGOTIATOR"
AuthCommand = 60008
Enact = "YES"
AuthMethodsList = "NTSSPI,KERBEROS"
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
Authentication = "NO"
Encryption = "NO"
Integrity = "NO"
SessionDuration = "86400"
UseSession = "YES"
Sid = "electrabel:2064:1278514941:2"
ValidCommands = "60000,60008,60017,60004,60007,457"
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
ServerCommandSock = "<172.17.7.223:4403>"
Command = 60008
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: startCommand succeeded.
07/07 15:02:21 (fd:4) (pid:1436) Authorizing server '*/172.17.7.223'.
07/07 15:02:21 (fd:4) (pid:1436) Destroying Daemon object:
07/07 15:02:21 (fd:4) (pid:1436) Type: 1 (any), Name: (null), Addr: <172.17.7.223:4398>
07/07 15:02:21 (fd:4) (pid:1436) FullHost: (null), Host: (null), Pool: (null), Port: -1
07/07 15:02:21 (fd:4) (pid:1436) IsLocal: N, IdStr: (null), Error: (null)
07/07 15:02:21 (fd:4) (pid:1436)  --- End of Daemon object info ---
07/07 15:02:21 (fd:4) (pid:1436) SEND [628] <172.17.7.223:4414> <172.17.7.223:4398>
07/07 15:02:21 (fd:4) (pid:1436) DaemonCore: Leaving SendAliveToParent() - success
07/07 15:02:21 (fd:4) (pid:1436) Return from Timer handler 5 (DaemonCore::SendAliveToParent)
07/07 15:02:21 (fd:4) (pid:1436) PRIV_CONDOR --> PRIV_CONDOR at ..\src\condor_daemon_core.V6\daemon_core.cpp:3548
07/07 15:02:21 (fd:4) (pid:1436) Calling Timer handler 6 (Time to negotiate)
07/07 15:02:21 (fd:4) (pid:1436) NEGOTIATOR_CYCLE_DELAY is undefined, using default value of 20
07/07 15:02:21 (fd:4) (pid:1436) ---------- Started Negotiation Cycle ----------
07/07 15:02:21 (fd:4) (pid:1436) Phase 1:  Obtaining ads from collector ...
07/07 15:02:21 (fd:4) (pid:1436)   Getting all public ads ...
07/07 15:02:21 (fd:4) (pid:1436) Trying to query collector <172.17.7.223:9618>
07/07 15:02:21 (fd:4) (pid:1436) NEGOTIATOR_TIMEOUT_MULTIPLIER is undefined, using default value of 0
07/07 15:02:21 (fd:4) (pid:1436) New Daemon obj (collector) name: "NULL", pool: "NULL", addr: "<172.17.7.223:9618>"
07/07 15:02:21 (fd:4) (pid:1436) Already have address, no info to locate
07/07 15:02:21 (fd:4) (pid:1436) Address "<172.17.7.223:9618>" specified but no name, looking up host info
07/07 15:02:21 (fd:4) (pid:1436) Trying to find full hostname from hostent
07/07 15:02:21 (fd:4) (pid:1436) Main name in hostent "electrabel.pylos.logilab.fr" is fully qualified
07/07 15:02:21 (fd:4) (pid:1436) Querying collector <172.17.7.223:9618> (electrabel.pylos.logilab.fr) with classad:
MyType = "Query"
TargetType = "Any"
Requirements = TRUE
07/07 15:02:21 (fd:4) (pid:1436)  --- End of Query ClassAd ---
07/07 15:02:21 (fd:4) (pid:1436) QUERY_TIMEOUT is undefined, using default value of 60
07/07 15:02:21 (fd:4) (pid:1436) selector 0012F408 resetting
07/07 15:02:21 (fd:4) (pid:1436) selector 0012F408 resetting
07/07 15:02:21 (fd:4) (pid:1436) selector 0012F408 adding fd 1744 ()
07/07 15:02:21 (fd:4) (pid:1436) selector 0012F408 adding fd 1744 ()
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:21 (fd:4) (pid:1436) CONNECT src=<172.17.7.223:4420> fd=1744 dst=<172.17.7.223:9618>
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: command 48 QUERY_ANY_ADS to collector at <172.17.7.223:9618> (electrabel.pylos.logilab.fr) from TCP port 4420 (blocking).
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: no cached key for {<172.17.7.223:9618>,<48>}.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: Security Policy:
MyType = ""
TargetType = ""
AuthMethods = "NTSSPI,KERBEROS"
CryptoMethods = "3DES,BLOWFISH"
OutgoingNegotiation = "PREFERRED"
Authentication = "OPTIONAL"
Encryption = "OPTIONAL"
Integrity = "OPTIONAL"
Enact = "NO"
Subsystem = "NEGOTIATOR"
ParentUniqueID = "electrabel:2064:1278514935"
ServerPid = 1436
SessionDuration = "86400"
NewSession = "YES"
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: negotiating security for command 48.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: sending DC_AUTHENTICATE command
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: sending following classad:
MyType = ""
TargetType = ""
AuthMethods = "NTSSPI,KERBEROS"
CryptoMethods = "3DES,BLOWFISH"
OutgoingNegotiation = "PREFERRED"
Authentication = "OPTIONAL"
Encryption = "OPTIONAL"
Integrity = "OPTIONAL"
Enact = "NO"
Subsystem = "NEGOTIATOR"
ParentUniqueID = "electrabel:2064:1278514935"
ServerPid = 1436
SessionDuration = "86400"
NewSession = "YES"
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
ServerCommandSock = "<172.17.7.223:4403>"
Command = 48
07/07 15:02:21 (fd:4) (pid:1436) selector 0012F114 resetting
07/07 15:02:21 (fd:4) (pid:1436) condor_write(fd=1744 collector at <172.17.7.223:9618> (electrabel.pylos.logilab.fr),,size=495,timeout=60,flags=0)
07/07 15:02:21 (fd:4) (pid:1436) selector 0012F114 adding fd 1744 ()
07/07 15:02:21 (fd:4) (pid:1436) selector 0012F114 adding fd 1744 ()
07/07 15:02:21 (fd:4) (pid:1436) selector 0012F114 adding fd 1744 ()
07/07 15:02:21 (fd:4) (pid:1436) selector 0012F114 adding fd 1744 ()
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe start [send] in condor_rw.cpp:357 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe start [send] in condor_rw.cpp:357 2364()
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe stop [send] in condor_rw.cpp:359 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe stop [send] in condor_rw.cpp:359 2364()
07/07 15:02:21 (fd:4) (pid:1436) selector 0012F198 resetting
07/07 15:02:21 (fd:4) (pid:1436) condor_read(fd=1744 collector at <172.17.7.223:9618> (electrabel.pylos.logilab.fr),,size=5,timeout=60,flags=0)
07/07 15:02:21 (fd:4) (pid:1436) selector 0012F198 adding fd 1744 ()
07/07 15:02:21 (fd:4) (pid:1436) condor_read(): fd=1744
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:21 (fd:4) (pid:1436) condor_read(): select returned 1
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:21 (fd:4) (pid:1436) selector 0012F17C resetting
07/07 15:02:21 (fd:4) (pid:1436) condor_read(fd=1744 collector at <172.17.7.223:9618> (electrabel.pylos.logilab.fr),,size=296,timeout=60,flags=0)
07/07 15:02:21 (fd:4) (pid:1436) selector 0012F17C adding fd 1744 ()
07/07 15:02:21 (fd:4) (pid:1436) condor_read(): fd=1744
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:21 (fd:4) (pid:1436) condor_read(): select returned 1
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: server responded with:
MyType = "(unknown type)"
TargetType = "(unknown type)"
Authentication = "NO"
Encryption = "NO"
Integrity = "NO"
AuthMethodsList = "NTSSPI,KERBEROS"
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
SessionDuration = "86400"
Enact = "YES"
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
07/07 15:02:21 (fd:4) (pid:1436) selector 0012F164 resetting
07/07 15:02:21 (fd:4) (pid:1436) condor_read(fd=1744 collector at <172.17.7.223:9618> (electrabel.pylos.logilab.fr),,size=5,timeout=60,flags=0)
07/07 15:02:21 (fd:4) (pid:1436) selector 0012F164 adding fd 1744 ()
07/07 15:02:21 (fd:4) (pid:1436) condor_read(): fd=1744
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:21 (fd:4) (pid:1436) condor_read(): select returned 1
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:21 (fd:4) (pid:1436) selector 0012F148 resetting
07/07 15:02:21 (fd:4) (pid:1436) condor_read(fd=1744 collector at <172.17.7.223:9618> (electrabel.pylos.logilab.fr),,size=147,timeout=60,flags=0)
07/07 15:02:21 (fd:4) (pid:1436) selector 0012F148 adding fd 1744 ()
07/07 15:02:21 (fd:4) (pid:1436) condor_read(): fd=1744
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:21 (fd:4) (pid:1436) condor_read(): select returned 1
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: received post-auth classad:
MyType = "(unknown type)"
TargetType = "(unknown type)"
Sid = "electrabel:1520:1278514941:1"
ValidCommands = "5,6,7,9,12,43,46,48,50,53,56,62,65,68,71,74,60007,457"
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: policy to be cached:
MyType = ""
TargetType = ""
OutgoingNegotiation = "PREFERRED"
Subsystem = "NEGOTIATOR"
Command = 48
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
Enact = "YES"
AuthMethodsList = "NTSSPI,KERBEROS"
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
Authentication = "NO"
Encryption = "NO"
Integrity = "NO"
SessionDuration = "86400"
UseSession = "YES"
Sid = "electrabel:1520:1278514941:1"
ValidCommands = "5,6,7,9,12,43,46,48,50,53,56,62,65,68,71,74,60007,457"
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: added session electrabel:1520:1278514941:1 to cache for 86400 seconds.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<5>} mapped to session electrabel:1520:1278514941:1.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<6>} mapped to session electrabel:1520:1278514941:1.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<7>} mapped to session electrabel:1520:1278514941:1.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<9>} mapped to session electrabel:1520:1278514941:1.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<12>} mapped to session electrabel:1520:1278514941:1.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<43>} mapped to session electrabel:1520:1278514941:1.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<46>} mapped to session electrabel:1520:1278514941:1.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<48>} mapped to session electrabel:1520:1278514941:1.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<50>} mapped to session electrabel:1520:1278514941:1.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<53>} mapped to session electrabel:1520:1278514941:1.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<56>} mapped to session electrabel:1520:1278514941:1.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<62>} mapped to session electrabel:1520:1278514941:1.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<65>} mapped to session electrabel:1520:1278514941:1.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<68>} mapped to session electrabel:1520:1278514941:1.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<71>} mapped to session electrabel:1520:1278514941:1.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<74>} mapped to session electrabel:1520:1278514941:1.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<60007>} mapped to session electrabel:1520:1278514941:1.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<457>} mapped to session electrabel:1520:1278514941:1.
07/07 15:02:21 (fd:4) (pid:1436) SECMAN: startCommand succeeded.
07/07 15:02:21 (fd:4) (pid:1436) Authorizing server '*/172.17.7.223'.
07/07 15:02:21 (fd:4) (pid:1436) selector 0012F460 resetting
07/07 15:02:21 (fd:4) (pid:1436) condor_write(fd=1744 collector at <172.17.7.223:9618> (electrabel.pylos.logilab.fr),,size=43,timeout=60,flags=0)
07/07 15:02:21 (fd:4) (pid:1436) selector 0012F460 adding fd 1744 ()
07/07 15:02:21 (fd:4) (pid:1436) selector 0012F460 adding fd 1744 ()
07/07 15:02:21 (fd:4) (pid:1436) selector 0012F460 adding fd 1744 ()
07/07 15:02:21 (fd:4) (pid:1436) selector 0012F460 adding fd 1744 ()
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:21 (fd:4) (pid:1436) Entering thread safe start [send] in condor_rw.cpp:357 2364()
07/07 15:02:21 (fd:4) (pid:1436) Leaving thread safe start [send] in condor_rw.cpp:357 2364()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe stop [send] in condor_rw.cpp:359 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe stop [send] in condor_rw.cpp:359 2364()
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F45C resetting
07/07 15:02:22 (fd:4) (pid:1436) condor_read(fd=1744 collector at <172.17.7.223:9618> (electrabel.pylos.logilab.fr),,size=5,timeout=60,flags=0)
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F45C adding fd 1744 ()
07/07 15:02:22 (fd:4) (pid:1436) condor_read(): fd=1744
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:22 (fd:4) (pid:1436) condor_read(): select returned 1
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F440 resetting
07/07 15:02:22 (fd:4) (pid:1436) condor_read(fd=1744 collector at <172.17.7.223:9618> (electrabel.pylos.logilab.fr),,size=8,timeout=60,flags=0)
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F440 adding fd 1744 ()
07/07 15:02:22 (fd:4) (pid:1436) condor_read(): fd=1744
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:22 (fd:4) (pid:1436) condor_read(): select returned 1
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:22 (fd:4) (pid:1436) CLOSE <172.17.7.223:4420> fd=1744
07/07 15:02:22 (fd:4) (pid:1436) Destroying Daemon object:
07/07 15:02:22 (fd:4) (pid:1436) Type: 5 (collector), Name: (null), Addr: <172.17.7.223:9618>
07/07 15:02:22 (fd:4) (pid:1436) FullHost: electrabel.pylos.logilab.fr, Host: electrabel, Pool: (null), Port: 9618
07/07 15:02:22 (fd:4) (pid:1436) IsLocal: N, IdStr: collector at <172.17.7.223:9618> (electrabel.pylos.logilab.fr), Error: (null)
07/07 15:02:22 (fd:4) (pid:1436)  --- End of Daemon object info ---
07/07 15:02:22 (fd:4) (pid:1436)   Sorting 0 ads ...
07/07 15:02:22 (fd:4) (pid:1436)   Getting startd private ads ...
07/07 15:02:22 (fd:4) (pid:1436) Trying to query collector <172.17.7.223:9618>
07/07 15:02:22 (fd:4) (pid:1436) NEGOTIATOR_TIMEOUT_MULTIPLIER is undefined, using default value of 0
07/07 15:02:22 (fd:4) (pid:1436) New Daemon obj (collector) name: "NULL", pool: "NULL", addr: "<172.17.7.223:9618>"
07/07 15:02:22 (fd:4) (pid:1436) Already have address, no info to locate
07/07 15:02:22 (fd:4) (pid:1436) Address "<172.17.7.223:9618>" specified but no name, looking up host info
07/07 15:02:22 (fd:4) (pid:1436) Trying to find full hostname from hostent
07/07 15:02:22 (fd:4) (pid:1436) Main name in hostent "electrabel.pylos.logilab.fr" is fully qualified
07/07 15:02:22 (fd:4) (pid:1436) Querying collector <172.17.7.223:9618> (electrabel.pylos.logilab.fr) with classad:
MyType = "Query"
TargetType = "Machine"
Requirements = TRUE
07/07 15:02:22 (fd:4) (pid:1436)  --- End of Query ClassAd ---
07/07 15:02:22 (fd:4) (pid:1436) QUERY_TIMEOUT is undefined, using default value of 60
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F408 resetting
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F408 resetting
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F408 adding fd 1740 ()
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F408 adding fd 1740 ()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:22 (fd:4) (pid:1436) CONNECT src=<172.17.7.223:4422> fd=1740 dst=<172.17.7.223:9618>
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: command 10 QUERY_STARTD_PVT_ADS to collector at <172.17.7.223:9618> (electrabel.pylos.logilab.fr) from TCP port 4422 (blocking).
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: no cached key for {<172.17.7.223:9618>,<10>}.
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: Security Policy:
MyType = ""
TargetType = ""
AuthMethods = "NTSSPI,KERBEROS"
CryptoMethods = "3DES,BLOWFISH"
OutgoingNegotiation = "PREFERRED"
Authentication = "OPTIONAL"
Encryption = "OPTIONAL"
Integrity = "OPTIONAL"
Enact = "NO"
Subsystem = "NEGOTIATOR"
ParentUniqueID = "electrabel:2064:1278514935"
ServerPid = 1436
SessionDuration = "86400"
NewSession = "YES"
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: negotiating security for command 10.
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: sending DC_AUTHENTICATE command
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: sending following classad:
MyType = ""
TargetType = ""
AuthMethods = "NTSSPI,KERBEROS"
CryptoMethods = "3DES,BLOWFISH"
OutgoingNegotiation = "PREFERRED"
Authentication = "OPTIONAL"
Encryption = "OPTIONAL"
Integrity = "OPTIONAL"
Enact = "NO"
Subsystem = "NEGOTIATOR"
ParentUniqueID = "electrabel:2064:1278514935"
ServerPid = 1436
SessionDuration = "86400"
NewSession = "YES"
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
ServerCommandSock = "<172.17.7.223:4403>"
Command = 10
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F114 resetting
07/07 15:02:22 (fd:4) (pid:1436) condor_write(fd=1740 collector at <172.17.7.223:9618> (electrabel.pylos.logilab.fr),,size=495,timeout=60,flags=0)
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F114 adding fd 1740 ()
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F114 adding fd 1740 ()
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F114 adding fd 1740 ()
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F114 adding fd 1740 ()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe start [send] in condor_rw.cpp:357 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe start [send] in condor_rw.cpp:357 2364()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe stop [send] in condor_rw.cpp:359 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe stop [send] in condor_rw.cpp:359 2364()
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F198 resetting
07/07 15:02:22 (fd:4) (pid:1436) condor_read(fd=1740 collector at <172.17.7.223:9618> (electrabel.pylos.logilab.fr),,size=5,timeout=60,flags=0)
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F198 adding fd 1740 ()
07/07 15:02:22 (fd:4) (pid:1436) condor_read(): fd=1740
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:22 (fd:4) (pid:1436) condor_read(): select returned 1
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F17C resetting
07/07 15:02:22 (fd:4) (pid:1436) condor_read(fd=1740 collector at <172.17.7.223:9618> (electrabel.pylos.logilab.fr),,size=296,timeout=60,flags=0)
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F17C adding fd 1740 ()
07/07 15:02:22 (fd:4) (pid:1436) condor_read(): fd=1740
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:22 (fd:4) (pid:1436) condor_read(): select returned 1
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: server responded with:
MyType = "(unknown type)"
TargetType = "(unknown type)"
Authentication = "NO"
Encryption = "NO"
Integrity = "NO"
AuthMethodsList = "NTSSPI,KERBEROS"
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
SessionDuration = "86400"
Enact = "YES"
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F164 resetting
07/07 15:02:22 (fd:4) (pid:1436) condor_read(fd=1740 collector at <172.17.7.223:9618> (electrabel.pylos.logilab.fr),,size=5,timeout=60,flags=0)
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F164 adding fd 1740 ()
07/07 15:02:22 (fd:4) (pid:1436) condor_read(): fd=1740
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:22 (fd:4) (pid:1436) condor_read(): select returned 1
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F148 resetting
07/07 15:02:22 (fd:4) (pid:1436) condor_read(fd=1740 collector at <172.17.7.223:9618> (electrabel.pylos.logilab.fr),,size=159,timeout=60,flags=0)
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F148 adding fd 1740 ()
07/07 15:02:22 (fd:4) (pid:1436) condor_read(): fd=1740
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:22 (fd:4) (pid:1436) condor_read(): select returned 1
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: received post-auth classad:
MyType = "(unknown type)"
TargetType = "(unknown type)"
Sid = "electrabel:1520:1278514942:2"
ValidCommands = "10,49,51,73,5,6,7,9,12,43,46,48,50,53,56,62,65,68,71,74,60007,457"
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: policy to be cached:
MyType = ""
TargetType = ""
OutgoingNegotiation = "PREFERRED"
Subsystem = "NEGOTIATOR"
Command = 10
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
Enact = "YES"
AuthMethodsList = "NTSSPI,KERBEROS"
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
Authentication = "NO"
Encryption = "NO"
Integrity = "NO"
SessionDuration = "86400"
UseSession = "YES"
Sid = "electrabel:1520:1278514942:2"
ValidCommands = "10,49,51,73,5,6,7,9,12,43,46,48,50,53,56,62,65,68,71,74,60007,457"
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: added session electrabel:1520:1278514942:2 to cache for 86400 seconds.
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<10>} mapped to session electrabel:1520:1278514942:2.
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<49>} mapped to session electrabel:1520:1278514942:2.
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<51>} mapped to session electrabel:1520:1278514942:2.
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<73>} mapped to session electrabel:1520:1278514942:2.
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<5>} mapped to session electrabel:1520:1278514942:2.
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<6>} mapped to session electrabel:1520:1278514942:2.
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<7>} mapped to session electrabel:1520:1278514942:2.
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<9>} mapped to session electrabel:1520:1278514942:2.
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<12>} mapped to session electrabel:1520:1278514942:2.
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<43>} mapped to session electrabel:1520:1278514942:2.
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<46>} mapped to session electrabel:1520:1278514942:2.
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<48>} mapped to session electrabel:1520:1278514942:2.
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<50>} mapped to session electrabel:1520:1278514942:2.
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<53>} mapped to session electrabel:1520:1278514942:2.
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<56>} mapped to session electrabel:1520:1278514942:2.
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<62>} mapped to session electrabel:1520:1278514942:2.
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<65>} mapped to session electrabel:1520:1278514942:2.
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<68>} mapped to session electrabel:1520:1278514942:2.
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<71>} mapped to session electrabel:1520:1278514942:2.
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<74>} mapped to session electrabel:1520:1278514942:2.
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<60007>} mapped to session electrabel:1520:1278514942:2.
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: command {<172.17.7.223:9618>,<457>} mapped to session electrabel:1520:1278514942:2.
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: startCommand succeeded.
07/07 15:02:22 (fd:4) (pid:1436) Authorizing server '*/172.17.7.223'.
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F460 resetting
07/07 15:02:22 (fd:4) (pid:1436) condor_write(fd=1740 collector at <172.17.7.223:9618> (electrabel.pylos.logilab.fr),,size=47,timeout=60,flags=0)
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F460 adding fd 1740 ()
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F460 adding fd 1740 ()
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F460 adding fd 1740 ()
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F460 adding fd 1740 ()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe start [send] in condor_rw.cpp:357 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe start [send] in condor_rw.cpp:357 2364()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe stop [send] in condor_rw.cpp:359 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe stop [send] in condor_rw.cpp:359 2364()
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F45C resetting
07/07 15:02:22 (fd:4) (pid:1436) condor_read(fd=1740 collector at <172.17.7.223:9618> (electrabel.pylos.logilab.fr),,size=5,timeout=60,flags=0)
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F45C adding fd 1740 ()
07/07 15:02:22 (fd:4) (pid:1436) condor_read(): fd=1740
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:22 (fd:4) (pid:1436) condor_read(): select returned 1
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F440 resetting
07/07 15:02:22 (fd:4) (pid:1436) condor_read(fd=1740 collector at <172.17.7.223:9618> (electrabel.pylos.logilab.fr),,size=8,timeout=60,flags=0)
07/07 15:02:22 (fd:4) (pid:1436) selector 0012F440 adding fd 1740 ()
07/07 15:02:22 (fd:4) (pid:1436) condor_read(): fd=1740
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:22 (fd:4) (pid:1436) condor_read(): select returned 1
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:22 (fd:4) (pid:1436) CLOSE <172.17.7.223:4422> fd=1740
07/07 15:02:22 (fd:4) (pid:1436) Destroying Daemon object:
07/07 15:02:22 (fd:4) (pid:1436) Type: 5 (collector), Name: (null), Addr: <172.17.7.223:9618>
07/07 15:02:22 (fd:4) (pid:1436) FullHost: electrabel.pylos.logilab.fr, Host: electrabel, Pool: (null), Port: 9618
07/07 15:02:22 (fd:4) (pid:1436) IsLocal: N, IdStr: collector at <172.17.7.223:9618> (electrabel.pylos.logilab.fr), Error: (null)
07/07 15:02:22 (fd:4) (pid:1436)  --- End of Daemon object info ---
07/07 15:02:22 (fd:4) (pid:1436) Got ads: 0 public and 0 private
07/07 15:02:22 (fd:4) (pid:1436) Public ads include 0 submitter, 0 startd
07/07 15:02:22 (fd:4) (pid:1436) Entering compute_significant_attrs()
07/07 15:02:22 (fd:4) (pid:1436) Phase 2:  Performing accounting ...
07/07 15:02:22 (fd:4) (pid:1436) (ACCOUNTANT) Updating priorities - AgingFactor=   1.000 , TimePassed=3
07/07 15:02:22 (fd:4) (pid:1436) CustomerName=Customer.alf@pylos.logilab.fr , Old Priority=0.500 , New Priority=0.500 , ResourcesUsed=0 , WeightedResourcesUsed=0.000000
07/07 15:02:22 (fd:4) (pid:1436) RecentUsage=   0.000 (unweighted    0.000), UnchargedTime=   0.000 (unweighted 0), AccumulatedUsage=7368.000 (unweighted 7368.000), BeginUsageTime=1277141103
07/07 15:02:22 (fd:4) (pid:1436) (Accountant) Checking Matches
07/07 15:02:22 (fd:4) (pid:1436) Previous Limits --
07/07 15:02:22 (fd:4) (pid:1436) Current Limits --
07/07 15:02:22 (fd:4) (pid:1436) Phase 3:  Sorting submitter ads by priority ...
07/07 15:02:22 (fd:4) (pid:1436) Phase 4.1:  Negotiating with schedds ...
07/07 15:02:22 (fd:4) (pid:1436)     numSlots = 0
07/07 15:02:22 (fd:4) (pid:1436)     slotWeightTotal = 0.000000
07/07 15:02:22 (fd:4) (pid:1436)     pieLeft = 0.000
07/07 15:02:22 (fd:4) (pid:1436)     NormalFactor = 0.000000
07/07 15:02:22 (fd:4) (pid:1436)     MaxPrioValue = 0.000000
07/07 15:02:22 (fd:4) (pid:1436)     NumSubmitterAds = 0
07/07 15:02:22 (fd:4) (pid:1436) ---------- Finished Negotiation Cycle ----------
07/07 15:02:22 (fd:4) (pid:1436) Return from Timer handler 6 (Time to negotiate)
07/07 15:02:22 (fd:4) (pid:1436) PRIV_CONDOR --> PRIV_CONDOR at ..\src\condor_daemon_core.V6\daemon_core.cpp:3548
07/07 15:02:22 (fd:4) (pid:1436) DaemonCore Timeout() Complete, returning 0 
07/07 15:02:22 (fd:4) (pid:1436) selector 0012FA70 resetting
07/07 15:02:22 (fd:4) (pid:1436) selector 0012FA70 adding fd 1772 ()
07/07 15:02:22 (fd:4) (pid:1436) selector 0012FA70 adding fd 1768 ()
07/07 15:02:22 (fd:4) (pid:1436) selector 0012FA70 adding fd 1788 ()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:22 (fd:4) (pid:1436) In DaemonCore Timeout()
07/07 15:02:22 (fd:4) (pid:1436) 
07/07 15:02:22 (fd:4) (pid:1436) DaemonCore--> Timers
07/07 15:02:22 (fd:4) (pid:1436) DaemonCore--> ~~~~~~
07/07 15:02:22 (fd:4) (pid:1436) DaemonCore--> id = 7, when = 1278514940, period = 300, handler_descrip=<Update Collector>
07/07 15:02:22 (fd:4) (pid:1436) DaemonCore--> id = 8, when = 1278515000, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:22 (fd:4) (pid:1436) DaemonCore--> id = 6, when = 1278515002, period = 60, handler_descrip=<Time to negotiate>
07/07 15:02:22 (fd:4) (pid:1436) DaemonCore--> id = 2, when = 1278515240, period = 300, handler_descrip=<check_session_cache>
07/07 15:02:22 (fd:4) (pid:1436) DaemonCore--> id = 5, when = 1278516111, period = 1170, handler_descrip=<DaemonCore::SendAliveToParent>
07/07 15:02:22 (fd:4) (pid:1436) DaemonCore--> id = 3, when = 1278516741, period = 1801, handler_descrip=<handle_cookie_refresh>
07/07 15:02:22 (fd:4) (pid:1436) DaemonCore--> id = 9, when = 1278543740, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:22 (fd:4) (pid:1436) DaemonCore--> id = 4, when = 1278544042, period = 29103, handler_descrip=<DaemonCore::refreshDNS()>
07/07 15:02:22 (fd:4) (pid:1436) 
07/07 15:02:22 (fd:4) (pid:1436) Calling Timer handler 7 (Update Collector)
07/07 15:02:22 (fd:4) (pid:1436) enter Matchmaker::updateCollector
07/07 15:02:22 (fd:4) (pid:1436) Trying to update collector <172.17.7.223:9618>
07/07 15:02:22 (fd:4) (pid:1436) Attempting to send update via UDP to collector electrabel.pylos.logilab.fr <172.17.7.223:9618>
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: command 49 UPDATE_NEGOTIATOR_AD to collector electrabel.pylos.logilab.fr from UDP port 4423 (non-blocking).
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: using session electrabel:1520:1278514942:2 for {<172.17.7.223:9618>,<49>}.
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: found cached session id electrabel:1520:1278514942:2 for {<172.17.7.223:9618>,<49>}.
07/07 15:02:22 (fd:4) (pid:1436) SEC_DEBUG_PRINT_KEYS is undefined, using default value of False
MyType = ""
TargetType = ""
OutgoingNegotiation = "PREFERRED"
Subsystem = "NEGOTIATOR"
Command = 10
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
Enact = "YES"
AuthMethodsList = "NTSSPI,KERBEROS"
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
Authentication = "NO"
Encryption = "NO"
Integrity = "NO"
SessionDuration = "86400"
UseSession = "YES"
Sid = "electrabel:1520:1278514942:2"
ValidCommands = "10,49,51,73,5,6,7,9,12,43,46,48,50,53,56,62,65,68,71,74,60007,457"
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: Security Policy:
MyType = ""
TargetType = ""
OutgoingNegotiation = "PREFERRED"
Subsystem = "NEGOTIATOR"
Command = 10
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
Enact = "YES"
AuthMethodsList = "NTSSPI,KERBEROS"
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
Authentication = "NO"
Encryption = "NO"
Integrity = "NO"
SessionDuration = "86400"
UseSession = "YES"
Sid = "electrabel:1520:1278514942:2"
ValidCommands = "10,49,51,73,5,6,7,9,12,43,46,48,50,53,56,62,65,68,71,74,60007,457"
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: negotiating security for command 49.
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: UDP, m_have_session == 1, peer_can_negotiate == 1
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: UDP has session electrabel:1520:1278514942:2.
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: sending DC_AUTHENTICATE command
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: sending following classad:
MyType = ""
TargetType = ""
OutgoingNegotiation = "PREFERRED"
Subsystem = "NEGOTIATOR"
Enact = "YES"
AuthMethodsList = "NTSSPI,KERBEROS"
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
Authentication = "NO"
Encryption = "NO"
Integrity = "NO"
SessionDuration = "86400"
UseSession = "YES"
Sid = "electrabel:1520:1278514942:2"
ValidCommands = "10,49,51,73,5,6,7,9,12,43,46,48,50,53,56,62,65,68,71,74,60007,457"
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
ServerCommandSock = "<172.17.7.223:4403>"
Command = 49
07/07 15:02:22 (fd:4) (pid:1436) SECMAN: startCommand succeeded.
07/07 15:02:22 (fd:4) (pid:1436) Authorizing server '*/172.17.7.223'.
07/07 15:02:22 (fd:4) (pid:1436) SEND [1000] <172.17.7.223:4423> <172.17.7.223:9618>
07/07 15:02:22 (fd:4) (pid:1436) SEND [27] <172.17.7.223:4423> <172.17.7.223:9618>
07/07 15:02:22 (fd:4) (pid:1436) In reset_timer(), id=7, time=300, period=300
07/07 15:02:22 (fd:4) (pid:1436) exit Matchmaker::UpdateCollector
07/07 15:02:22 (fd:4) (pid:1436) Return from Timer handler 7 (Update Collector)
07/07 15:02:22 (fd:4) (pid:1436) PRIV_CONDOR --> PRIV_CONDOR at ..\src\condor_daemon_core.V6\daemon_core.cpp:3548
07/07 15:02:22 (fd:4) (pid:1436) DaemonCore Timeout() Complete, returning 58 
07/07 15:02:22 (fd:4) (pid:1436) selector 0012FA70 resetting
07/07 15:02:22 (fd:4) (pid:1436) selector 0012FA70 adding fd 1772 ()
07/07 15:02:22 (fd:4) (pid:1436) selector 0012FA70 adding fd 1768 ()
07/07 15:02:22 (fd:4) (pid:1436) selector 0012FA70 adding fd 1788 ()
07/07 15:02:22 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:22 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:32 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:32 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:32 (fd:4) (pid:1436) ACCEPT from=<172.17.7.223:4436> newfd=1740 to=<172.17.7.223:4403>
07/07 15:02:32 (fd:4) (pid:1436) in DaemonCore NewTimer()
07/07 15:02:32 (fd:4) (pid:1436) 
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> Timers
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> ~~~~~~
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 8, when = 1278515000, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 6, when = 1278515002, period = 60, handler_descrip=<Time to negotiate>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 10, when = 1278515152, period = 0, handler_descrip=<DaemonCore::HandleReqSocketTimerHandler>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 2, when = 1278515240, period = 300, handler_descrip=<check_session_cache>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 7, when = 1278515242, period = 300, handler_descrip=<Update Collector>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 5, when = 1278516111, period = 1170, handler_descrip=<DaemonCore::SendAliveToParent>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 3, when = 1278516741, period = 1801, handler_descrip=<handle_cookie_refresh>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 9, when = 1278543740, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 4, when = 1278544042, period = 29103, handler_descrip=<DaemonCore::refreshDNS()>
07/07 15:02:32 (fd:4) (pid:1436) 
07/07 15:02:32 (fd:4) (pid:1436) leaving DaemonCore NewTimer, id=10
07/07 15:02:32 (fd:4) (pid:1436) 
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> Sockets Registered
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> 0: 1772 <NULL> DC Command Handler
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> 1: 1768 <NULL> DC Command Handler
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> 2: 1740 Incoming command DaemonCore::HandleReqSocketHandler
07/07 15:02:32 (fd:4) (pid:1436) 
07/07 15:02:32 (fd:4) (pid:1436) PRIV_CONDOR --> PRIV_CONDOR at ..\src\condor_daemon_core.V6\daemon_core.cpp:3548
07/07 15:02:32 (fd:4) (pid:1436) In DaemonCore Timeout()
07/07 15:02:32 (fd:4) (pid:1436) 
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> Timers
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> ~~~~~~
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 8, when = 1278515000, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 6, when = 1278515002, period = 60, handler_descrip=<Time to negotiate>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 10, when = 1278515152, period = 0, handler_descrip=<DaemonCore::HandleReqSocketTimerHandler>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 2, when = 1278515240, period = 300, handler_descrip=<check_session_cache>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 7, when = 1278515242, period = 300, handler_descrip=<Update Collector>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 5, when = 1278516111, period = 1170, handler_descrip=<DaemonCore::SendAliveToParent>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 3, when = 1278516741, period = 1801, handler_descrip=<handle_cookie_refresh>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 9, when = 1278543740, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 4, when = 1278544042, period = 29103, handler_descrip=<DaemonCore::refreshDNS()>
07/07 15:02:32 (fd:4) (pid:1436) 
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore Timeout() Complete, returning 48 
07/07 15:02:32 (fd:4) (pid:1436) selector 0012FA70 resetting
07/07 15:02:32 (fd:4) (pid:1436) selector 0012FA70 adding fd 1772 ()
07/07 15:02:32 (fd:4) (pid:1436) selector 0012FA70 adding fd 1768 ()
07/07 15:02:32 (fd:4) (pid:1436) selector 0012FA70 adding fd 1740 ()
07/07 15:02:32 (fd:4) (pid:1436) selector 0012FA70 adding fd 1788 ()
07/07 15:02:32 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:32 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:32 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:32 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:32 (fd:4) (pid:1436) Calling Handler <DaemonCore::HandleReqSocketHandler> for Socket <Incoming command>
07/07 15:02:32 (fd:4) (pid:1436) Calling Handler <DaemonCore::HandleReqSocketHandler> (2)
07/07 15:02:32 (fd:4) (pid:1436) In cancel_timer(), id=10
07/07 15:02:32 (fd:4) (pid:1436) Cancel_Socket: cancelled socket 2 <Incoming command> 00C4DA48
07/07 15:02:32 (fd:4) (pid:1436) 
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> Sockets Registered
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> 0: 1772 <NULL> DC Command Handler
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> 1: 1768 <NULL> DC Command Handler
07/07 15:02:32 (fd:4) (pid:1436) 
07/07 15:02:32 (fd:4) (pid:1436) selector 0012F744 resetting
07/07 15:02:32 (fd:4) (pid:1436) condor_read(fd=1740 <172.17.7.223:4436>,,size=4,timeout=1,flags=2)
07/07 15:02:32 (fd:4) (pid:1436) selector 0012F744 adding fd 1740 ()
07/07 15:02:32 (fd:4) (pid:1436) condor_read(): fd=1740
07/07 15:02:32 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:32 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:32 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:32 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:32 (fd:4) (pid:1436) condor_read(): select returned 1
07/07 15:02:32 (fd:4) (pid:1436) Entering thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:32 (fd:4) (pid:1436) Leaving thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:32 (fd:4) (pid:1436) Entering thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:32 (fd:4) (pid:1436) Leaving thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:32 (fd:4) (pid:1436) selector 0012F6A4 resetting
07/07 15:02:32 (fd:4) (pid:1436) condor_read(fd=1740 <172.17.7.223:4436>,,size=5,timeout=1,flags=0)
07/07 15:02:32 (fd:4) (pid:1436) selector 0012F6A4 adding fd 1740 ()
07/07 15:02:32 (fd:4) (pid:1436) condor_read(): fd=1740
07/07 15:02:32 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:32 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:32 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:32 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:32 (fd:4) (pid:1436) condor_read(): select returned 1
07/07 15:02:32 (fd:4) (pid:1436) Entering thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:32 (fd:4) (pid:1436) Leaving thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:32 (fd:4) (pid:1436) Entering thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:32 (fd:4) (pid:1436) Leaving thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:32 (fd:4) (pid:1436) selector 0012F688 resetting
07/07 15:02:32 (fd:4) (pid:1436) condor_read(fd=1740 <172.17.7.223:4436>,,size=463,timeout=1,flags=0)
07/07 15:02:32 (fd:4) (pid:1436) selector 0012F688 adding fd 1740 ()
07/07 15:02:32 (fd:4) (pid:1436) condor_read(): fd=1740
07/07 15:02:32 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:32 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:32 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:32 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:32 (fd:4) (pid:1436) condor_read(): select returned 1
07/07 15:02:32 (fd:4) (pid:1436) Entering thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:32 (fd:4) (pid:1436) Leaving thread safe start [recv] in condor_rw.cpp:156 2364()
07/07 15:02:32 (fd:4) (pid:1436) Entering thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:32 (fd:4) (pid:1436) Leaving thread safe stop [recv] in condor_rw.cpp:158 2364()
07/07 15:02:32 (fd:4) (pid:1436) DC_AUTHENTICATE: received DC_AUTHENTICATE from <172.17.7.223:4436>
07/07 15:02:32 (fd:4) (pid:1436) DC_AUTHENTICATE: received following ClassAd:
MyType = "(unknown type)"
TargetType = "(unknown type)"
AuthMethods = "NTSSPI,KERBEROS"
CryptoMethods = "3DES,BLOWFISH"
OutgoingNegotiation = "PREFERRED"
Authentication = "OPTIONAL"
Encryption = "OPTIONAL"
Integrity = "OPTIONAL"
Enact = "NO"
Subsystem = "MASTER"
ServerPid = 2064
SessionDuration = "86400"
NewSession = "YES"
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
ServerCommandSock = "<172.17.7.223:4398>"
Command = 60010
AuthCommand = 60000
07/07 15:02:32 (fd:4) (pid:1436) DC_AUTHENTICATE: our_policy:
MyType = ""
TargetType = ""
AuthMethods = "NTSSPI,KERBEROS"
CryptoMethods = "3DES,BLOWFISH"
OutgoingNegotiation = "PREFERRED"
Authentication = "OPTIONAL"
Encryption = "OPTIONAL"
Integrity = "OPTIONAL"
Enact = "NO"
Subsystem = "NEGOTIATOR"
ParentUniqueID = "electrabel:2064:1278514935"
ServerPid = 1436
SessionDuration = "86400"
07/07 15:02:32 (fd:4) (pid:1436) DC_AUTHENTICATE: the_policy:
MyType = ""
TargetType = ""
Authentication = "NO"
Encryption = "NO"
Integrity = "NO"
AuthMethodsList = "NTSSPI,KERBEROS"
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
SessionDuration = "86400"
Enact = "YES"
07/07 15:02:32 (fd:4) (pid:1436) SECMAN: Sending following response ClassAd:
MyType = ""
TargetType = ""
Authentication = "NO"
Encryption = "NO"
Integrity = "NO"
AuthMethodsList = "NTSSPI,KERBEROS"
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
SessionDuration = "86400"
Enact = "YES"
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
07/07 15:02:32 (fd:4) (pid:1436) selector 0012F6A8 resetting
07/07 15:02:32 (fd:4) (pid:1436) condor_write(fd=1740 <172.17.7.223:4436>,,size=301,timeout=20,flags=0)
07/07 15:02:32 (fd:4) (pid:1436) selector 0012F6A8 adding fd 1740 ()
07/07 15:02:32 (fd:4) (pid:1436) selector 0012F6A8 adding fd 1740 ()
07/07 15:02:32 (fd:4) (pid:1436) selector 0012F6A8 adding fd 1740 ()
07/07 15:02:32 (fd:4) (pid:1436) selector 0012F6A8 adding fd 1740 ()
07/07 15:02:32 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:32 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:32 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:32 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:32 (fd:4) (pid:1436) Entering thread safe start [send] in condor_rw.cpp:357 2364()
07/07 15:02:32 (fd:4) (pid:1436) Leaving thread safe start [send] in condor_rw.cpp:357 2364()
07/07 15:02:32 (fd:4) (pid:1436) Entering thread safe stop [send] in condor_rw.cpp:359 2364()
07/07 15:02:32 (fd:4) (pid:1436) Leaving thread safe stop [send] in condor_rw.cpp:359 2364()
07/07 15:02:32 (fd:4) (pid:1436) DC_AUTHENTICATE: not authenticating.
07/07 15:02:32 (fd:4) (pid:1436) DC_AUTHENTICATE: sending session ad:
MyType = ""
TargetType = ""
Sid = "electrabel:1436:1278514952:0"
ValidCommands = "60000,60008,60017,421,60004,60007,451,457,463"
07/07 15:02:32 (fd:4) (pid:1436) selector 0012F6A8 resetting
07/07 15:02:32 (fd:4) (pid:1436) condor_write(fd=1740 <172.17.7.223:4436>,,size=144,timeout=20,flags=0)
07/07 15:02:32 (fd:4) (pid:1436) selector 0012F6A8 adding fd 1740 ()
07/07 15:02:32 (fd:4) (pid:1436) selector 0012F6A8 adding fd 1740 ()
07/07 15:02:32 (fd:4) (pid:1436) selector 0012F6A8 adding fd 1740 ()
07/07 15:02:32 (fd:4) (pid:1436) selector 0012F6A8 adding fd 1740 ()
07/07 15:02:32 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:32 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:32 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:32 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:32 (fd:4) (pid:1436) Entering thread safe start [send] in condor_rw.cpp:357 2364()
07/07 15:02:32 (fd:4) (pid:1436) Leaving thread safe start [send] in condor_rw.cpp:357 2364()
07/07 15:02:32 (fd:4) (pid:1436) Entering thread safe stop [send] in condor_rw.cpp:359 2364()
07/07 15:02:32 (fd:4) (pid:1436) Leaving thread safe stop [send] in condor_rw.cpp:359 2364()
07/07 15:02:32 (fd:4) (pid:1436) DC_AUTHENTICATE: sent session electrabel:1436:1278514952:0 info!
07/07 15:02:32 (fd:4) (pid:1436) SEC_SESSION_DURATION_SLOP is undefined, using default value of 20
07/07 15:02:32 (fd:4) (pid:1436) DC_AUTHENTICATE: added incoming session id electrabel:1436:1278514952:0 to cache for 86420 seconds (return address is <172.17.7.223:4398>).
MyType = ""
TargetType = ""
Authentication = "NO"
Encryption = "NO"
Integrity = "NO"
AuthMethodsList = "NTSSPI,KERBEROS"
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
SessionDuration = "86400"
Enact = "YES"
Subsystem = "MASTER"
ServerCommandSock = "<172.17.7.223:4398>"
ServerPid = 2064
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
Sid = "electrabel:1436:1278514952:0"
ValidCommands = "60000,60008,60017,421,60004,60007,451,457,463"
07/07 15:02:32 (fd:4) (pid:1436) CLOSE <172.17.7.223:4403> fd=1740
07/07 15:02:32 (fd:4) (pid:1436) Return from Handler <DaemonCore::HandleReqSocketHandler>
07/07 15:02:32 (fd:4) (pid:1436) PRIV_CONDOR --> PRIV_CONDOR at ..\src\condor_daemon_core.V6\daemon_core.cpp:3548
07/07 15:02:32 (fd:4) (pid:1436) In DaemonCore Timeout()
07/07 15:02:32 (fd:4) (pid:1436) 
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> Timers
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> ~~~~~~
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 8, when = 1278515000, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 6, when = 1278515002, period = 60, handler_descrip=<Time to negotiate>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 2, when = 1278515240, period = 300, handler_descrip=<check_session_cache>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 7, when = 1278515242, period = 300, handler_descrip=<Update Collector>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 5, when = 1278516111, period = 1170, handler_descrip=<DaemonCore::SendAliveToParent>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 3, when = 1278516741, period = 1801, handler_descrip=<handle_cookie_refresh>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 9, when = 1278543740, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 4, when = 1278544042, period = 29103, handler_descrip=<DaemonCore::refreshDNS()>
07/07 15:02:32 (fd:4) (pid:1436) 
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore Timeout() Complete, returning 48 
07/07 15:02:32 (fd:4) (pid:1436) selector 0012FA70 resetting
07/07 15:02:32 (fd:4) (pid:1436) selector 0012FA70 adding fd 1772 ()
07/07 15:02:32 (fd:4) (pid:1436) selector 0012FA70 adding fd 1768 ()
07/07 15:02:32 (fd:4) (pid:1436) selector 0012FA70 adding fd 1788 ()
07/07 15:02:32 (fd:4) (pid:1436) Entering thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:32 (fd:4) (pid:1436) Leaving thread safe start [select] in selector.cpp:311 2364()
07/07 15:02:32 (fd:4) (pid:1436) Entering thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:32 (fd:4) (pid:1436) Leaving thread safe stop [select] in selector.cpp:317 2364()
07/07 15:02:32 (fd:4) (pid:1436) RECV 582 bytes at <172.17.7.223:4403> from <172.17.7.223:4435>
07/07 15:02:32 (fd:4) (pid:1436) 	Full msg [582 bytes]
07/07 15:02:32 (fd:4) (pid:1436) DC_AUTHENTICATE: received UDP packet from <172.17.7.223:4435>.
07/07 15:02:32 (fd:4) (pid:1436) DC_AUTHENTICATE: received DC_AUTHENTICATE from <172.17.7.223:4435>
07/07 15:02:32 (fd:4) (pid:1436) DC_AUTHENTICATE: received following ClassAd:
MyType = "(unknown type)"
TargetType = "(unknown type)"
ServerPid = 2064
OutgoingNegotiation = "PREFERRED"
Subsystem = "MASTER"
AuthCommand = 60000
Enact = "YES"
AuthMethodsList = "NTSSPI,KERBEROS"
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
Authentication = "NO"
Encryption = "NO"
Integrity = "NO"
SessionDuration = "86400"
UseSession = "YES"
Sid = "electrabel:1436:1278514952:0"
ValidCommands = "60000,60008,60017,421,60004,60007,451,457,463"
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
ServerCommandSock = "<172.17.7.223:4398>"
Command = 60000
07/07 15:02:32 (fd:4) (pid:1436) DC_AUTHENTICATE: resuming session id electrabel:1436:1278514952:0 with return address <172.17.7.223:4398>:
07/07 15:02:32 (fd:4) (pid:1436) DC_AUTHENTICATE: Cached Session:
MyType = ""
TargetType = ""
Authentication = "NO"
Encryption = "NO"
Integrity = "NO"
AuthMethodsList = "NTSSPI,KERBEROS"
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
SessionDuration = "86400"
Enact = "YES"
Subsystem = "MASTER"
ServerCommandSock = "<172.17.7.223:4398>"
ServerPid = 2064
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
Sid = "electrabel:1436:1278514952:0"
ValidCommands = "60000,60008,60017,421,60004,60007,451,457,463"
07/07 15:02:32 (fd:4) (pid:1436) DC_AUTHENTICATE: setting sock->decode()
07/07 15:02:32 (fd:4) (pid:1436) DC_AUTHENTICATE: allowing an empty message for sock.
07/07 15:02:32 (fd:4) (pid:1436) DC_AUTHENTICATE: Success.
07/07 15:02:32 (fd:4) (pid:1436) IPVERIFY: matched user * from 172.17.7.223 to allow list
07/07 15:02:32 (fd:4) (pid:1436) Adding to resolved authorization table: */172.17.7.223: DAEMON
07/07 15:02:32 (fd:4) (pid:1436) PERMISSION GRANTED to unauthenticated user from host 172.17.7.223 for command 60000 (DC_RAISESIGNAL), access level DAEMON: reason: DAEMON authorization policy allows IP address 172.17.7.223; identifiers used for this remote host: 172.17.7.223,electrabel.pylos.logilab.fr
07/07 15:02:32 (fd:4) (pid:1436) Received UDP command 60000 (DC_RAISESIGNAL) from  <172.17.7.223:4435>, access level DAEMON
07/07 15:02:32 (fd:4) (pid:1436) Calling HandleReq <HandleSigCommand()> (0)
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore: received Signal 15 (SIGTERM), raising event handle_dc_sigterm()
07/07 15:02:32 (fd:4) (pid:1436) Return from HandleReq <HandleSigCommand()> (handler: 0.000s, sec: 0.031s)
07/07 15:02:32 (fd:4) (pid:1436) PRIV_CONDOR --> PRIV_CONDOR at ..\src\condor_daemon_core.V6\daemon_core.cpp:3548
07/07 15:02:32 (fd:4) (pid:1436) Calling Handler <handle_dc_sigterm()> for Signal 15 <SIGTERM>
07/07 15:02:32 (fd:4) (pid:1436) Got SIGTERM. Performing graceful shutdown.
07/07 15:02:32 (fd:4) (pid:1436) in DaemonCore NewTimer()
07/07 15:02:32 (fd:4) (pid:1436) 
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> Timers
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> ~~~~~~
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 8, when = 1278515000, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 6, when = 1278515002, period = 60, handler_descrip=<Time to negotiate>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 2, when = 1278515240, period = 300, handler_descrip=<check_session_cache>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 7, when = 1278515242, period = 300, handler_descrip=<Update Collector>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 5, when = 1278516111, period = 1170, handler_descrip=<DaemonCore::SendAliveToParent>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 3, when = 1278516741, period = 1801, handler_descrip=<handle_cookie_refresh>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 11, when = 1278516752, period = 0, handler_descrip=<main_shutdown_fast>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 9, when = 1278543740, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:32 (fd:4) (pid:1436) DaemonCore--> id = 4, when = 1278544042, period = 29103, handler_descrip=<DaemonCore::refreshDNS()>
07/07 15:02:32 (fd:4) (pid:1436) 
07/07 15:02:32 (fd:4) (pid:1436) leaving DaemonCore NewTimer, id=11
07/07 15:02:32 (fd:4) (pid:1436) Started timer to call main_shutdown_fast in 1800 seconds
07/07 15:02:32 (fd:4) (pid:1436) Trying to update collector <172.17.7.223:9618>
07/07 15:02:32 (fd:4) (pid:1436) Attempting to send update via UDP to collector electrabel.pylos.logilab.fr <172.17.7.223:9618>
07/07 15:02:32 (fd:4) (pid:1436) SECMAN: command 51 INVALIDATE_NEGOTIATOR_ADS to collector electrabel.pylos.logilab.fr from UDP port 4439 (blocking).
07/07 15:02:32 (fd:4) (pid:1436) SECMAN: using session electrabel:1520:1278514942:2 for {<172.17.7.223:9618>,<51>}.
07/07 15:02:32 (fd:4) (pid:1436) SECMAN: found cached session id electrabel:1520:1278514942:2 for {<172.17.7.223:9618>,<51>}.
07/07 15:02:32 (fd:4) (pid:1436) SEC_DEBUG_PRINT_KEYS is undefined, using default value of False
MyType = ""
TargetType = ""
OutgoingNegotiation = "PREFERRED"
Subsystem = "NEGOTIATOR"
Command = 10
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
Enact = "YES"
AuthMethodsList = "NTSSPI,KERBEROS"
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
Authentication = "NO"
Encryption = "NO"
Integrity = "NO"
SessionDuration = "86400"
UseSession = "YES"
Sid = "electrabel:1520:1278514942:2"
ValidCommands = "10,49,51,73,5,6,7,9,12,43,46,48,50,53,56,62,65,68,71,74,60007,457"
07/07 15:02:32 (fd:4) (pid:1436) SECMAN: Security Policy:
MyType = ""
TargetType = ""
OutgoingNegotiation = "PREFERRED"
Subsystem = "NEGOTIATOR"
Command = 10
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
Enact = "YES"
AuthMethodsList = "NTSSPI,KERBEROS"
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
Authentication = "NO"
Encryption = "NO"
Integrity = "NO"
SessionDuration = "86400"
UseSession = "YES"
Sid = "electrabel:1520:1278514942:2"
ValidCommands = "10,49,51,73,5,6,7,9,12,43,46,48,50,53,56,62,65,68,71,74,60007,457"
07/07 15:02:32 (fd:4) (pid:1436) SECMAN: negotiating security for command 51.
07/07 15:02:32 (fd:4) (pid:1436) SECMAN: UDP, m_have_session == 1, peer_can_negotiate == 1
07/07 15:02:32 (fd:4) (pid:1436) SECMAN: UDP has session electrabel:1520:1278514942:2.
07/07 15:02:32 (fd:4) (pid:1436) SECMAN: sending DC_AUTHENTICATE command
07/07 15:02:32 (fd:4) (pid:1436) SECMAN: sending following classad:
MyType = ""
TargetType = ""
OutgoingNegotiation = "PREFERRED"
Subsystem = "NEGOTIATOR"
Enact = "YES"
AuthMethodsList = "NTSSPI,KERBEROS"
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
Authentication = "NO"
Encryption = "NO"
Integrity = "NO"
SessionDuration = "86400"
UseSession = "YES"
Sid = "electrabel:1520:1278514942:2"
ValidCommands = "10,49,51,73,5,6,7,9,12,43,46,48,50,53,56,62,65,68,71,74,60007,457"
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
ServerCommandSock = "<172.17.7.223:4403>"
Command = 51
07/07 15:02:32 (fd:4) (pid:1436) SECMAN: startCommand succeeded.
07/07 15:02:32 (fd:4) (pid:1436) Authorizing server '*/172.17.7.223'.
07/07 15:02:32 (fd:4) (pid:1436) SEND [728] <172.17.7.223:4439> <172.17.7.223:9618>
07/07 15:02:32 (fd:4) (pid:1436) KEYCACHEENTRY: deleted: 00C4C640
07/07 15:02:32 (fd:4) (pid:1436) KEYCACHEENTRY: deleted: 00C78458
07/07 15:02:32 (fd:4) (pid:1436) KEYCACHEENTRY: deleted: 00C556F0
07/07 15:02:32 (fd:4) (pid:1436) KEYCACHEENTRY: deleted: 00C5A1B0
07/07 15:02:32 (fd:4) (pid:1436) KEYCACHE: deleted: 00C000B0
07/07 15:02:32 (fd:4) (pid:1436) CLOSE <172.17.7.223:4403> fd=1772
07/07 15:02:32 (fd:4) (pid:1436) Destroying Daemon object:
07/07 15:02:32 (fd:4) (pid:1436) Type: 5 (collector), Name: electrabel.pylos.logilab.fr, Addr: <172.17.7.223:9618>
07/07 15:02:32 (fd:4) (pid:1436) FullHost: electrabel.pylos.logilab.fr, Host: electrabel, Pool: electrabel.pylos.logilab.fr, Port: 9618
07/07 15:02:32 (fd:4) (pid:1436) IsLocal: N, IdStr: collector electrabel.pylos.logilab.fr, Error: (null)
07/07 15:02:32 (fd:4) (pid:1436)  --- End of Daemon object info ---
07/07 15:02:32 (fd:4) (pid:1436) CLOSE <127.0.0.1:4405> fd=292
07/07 15:02:32 (fd:4) (pid:1436) CLOSE <127.0.0.1:4406> fd=1788
07/07 15:02:32 (fd:4) (pid:1436) **** condor_negotiator.exe (condor_NEGOTIATOR) pid 1436 EXITING WITH STATUS 0
