07/07 15:02:18 (fd:3) (pid:2360) LOGS_USE_TIMESTAMP is undefined, using default value of False
07/07 15:02:18 (fd:3) (pid:2360) Locale: English_United States.1252
07/07 15:02:18 (fd:3) (pid:2360) config: using subsystem 'CREDD', local ''
07/07 15:02:18 (fd:3) (pid:2360) Finding local host information, calling gethostname()
07/07 15:02:18 (fd:3) (pid:2360) gethostname() returned a host with no domain "electrabel"
07/07 15:02:18 (fd:3) (pid:2360) Trying to find full hostname and IP addr for "electrabel"
07/07 15:02:18 (fd:3) (pid:2360) Calling gethostbyname(electrabel)
07/07 15:02:18 (fd:3) (pid:2360) Found IP addr in hostent: 172.17.7.223
07/07 15:02:18 (fd:3) (pid:2360) Trying to find full hostname from hostent
07/07 15:02:18 (fd:3) (pid:2360) Main name in hostent "electrabel.pylos.logilab.fr" is fully qualified
07/07 15:02:18 (fd:3) (pid:2360) Trying to initialize local IP address (config file not read)
07/07 15:02:18 (fd:3) (pid:2360) Already found IP with gethostbyname()
07/07 15:02:18 (fd:3) (pid:2360) NET_REMAP_ENABLE is undefined, using default value of False
07/07 15:02:18 (fd:3) (pid:2360) ENABLE_RUNTIME_CONFIG is undefined, using default value of False
07/07 15:02:18 (fd:3) (pid:2360) ENABLE_PERSISTENT_CONFIG is undefined, using default value of False
07/07 15:02:18 (fd:3) (pid:2360) Trying to initialize local IP address (after reading config)
07/07 15:02:18 (fd:3) (pid:2360) NETWORK_INTERFACE not in config file, using existing value
07/07 15:02:18 (fd:3) (pid:2360) ABORT_ON_EXCEPTION is undefined, using default value of False
07/07 15:02:18 (fd:3) (pid:2360) Config 'CREDD_DEBUG': no prefix ==> 'D_COMMAND'
07/07 15:02:18 (fd:3) (pid:2360) Config 'CREDD_LOG': no prefix ==> '$(LOG)/CreddLog'
07/07 15:02:18 (fd:3) (pid:2360) Config 'MAX_CREDD_LOG': no prefix ==> '50000000'
07/07 15:02:18 (fd:3) (pid:2360) PRIV_UNKNOWN --> PRIV_CONDOR at ..\src\condor_daemon_core.V6\daemon_core_main.cpp:1834
07/07 15:02:18 (fd:3) (pid:2360) KEYCACHE: created: 00BCF730
07/07 15:02:18 (fd:3) (pid:2360) WANT_UDP_COMMAND_SOCKET is undefined, using default value of True
07/07 15:02:18 (fd:3) (pid:2360) ******************************************************
07/07 15:02:18 (fd:3) (pid:2360) ** condor_credd.exe (CONDOR_CREDD) STARTING UP
07/07 15:02:18 (fd:3) (pid:2360) ** C:\condor\bin\condor_credd.exe
07/07 15:02:18 (fd:3) (pid:2360) ** SubsystemInfo: name=CREDD type=DAEMON(11) class=DAEMON(1)
07/07 15:02:18 (fd:3) (pid:2360) ** Configuration: subsystem:CREDD local:<NONE> class:DAEMON
07/07 15:02:18 (fd:3) (pid:2360) ** $CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $
07/07 15:02:18 (fd:3) (pid:2360) ** $CondorPlatform: INTEL-WINNT50 $
07/07 15:02:18 (fd:3) (pid:2360) ** PID = 2360
07/07 15:02:18 (fd:3) (pid:2360) ** Log last touched time unavailable (No such file or directory)
07/07 15:02:18 (fd:3) (pid:2360) ******************************************************
07/07 15:02:18 (fd:3) (pid:2360) Using config source: C:\condor\condor_config
07/07 15:02:18 (fd:3) (pid:2360) Using local config sources: 
07/07 15:02:18 (fd:3) (pid:2360)    C:\condor/condor_config.local
07/07 15:02:18 (fd:3) (pid:2360) Config 'LOG': no prefix ==> '$(LOCAL_DIR)/log'
07/07 15:02:18 (fd:3) (pid:2360) Config 'BIN': no prefix ==> '$(RELEASE_DIR)/bin'
07/07 15:02:18 (fd:3) (pid:2360) LISTEN <127.0.0.1:4410> fd=1796
07/07 15:02:18 (fd:3) (pid:2360) CONNECT src=<127.0.0.1:4409> fd=292 dst=<127.0.0.1:4410>
07/07 15:02:18 (fd:3) (pid:2360) ACCEPT from=<127.0.0.1:4409> newfd=1788 to=<127.0.0.1:4410>
07/07 15:02:18 (fd:3) (pid:2360) CLOSE <127.0.0.1:4410> fd=1796
07/07 15:02:18 (fd:3) (pid:2360) Setting up command socket
07/07 15:02:18 (fd:3) (pid:2360) CONDOR_INHERIT: "2064 <172.17.7.223:4398> 0 416*6*0*0*0*0***1*<172.17.7.223:0>*0*0* 364*2*0*0*0*0***0*<172.17.7.223:0>* 0"
07/07 15:02:18 (fd:3) (pid:2360) Parent PID = 2064
07/07 15:02:18 (fd:3) (pid:2360) Parent Command Sock = <172.17.7.223:4398>
07/07 15:02:18 (fd:3) (pid:2360) Inheriting Command Sockets
07/07 15:02:18 (fd:3) (pid:2360) 
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> Sockets Registered
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> 0: 1772 <NULL> DC Command Handler
07/07 15:02:18 (fd:3) (pid:2360) 
07/07 15:02:18 (fd:3) (pid:2360) 
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> Sockets Registered
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> 0: 1772 <NULL> DC Command Handler
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> 1: 1768 <NULL> DC Command Handler
07/07 15:02:18 (fd:3) (pid:2360) 
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore: Command Socket at <172.17.7.223:9620>
07/07 15:02:18 (fd:3) (pid:2360) Config 'CREDD_ADDRESS_FILE': no prefix ==> '$(LOG)/.credd_address'
07/07 15:02:18 (fd:3) (pid:2360) 
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> Commands Registered
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:18 (fd:3) (pid:2360) 
07/07 15:02:18 (fd:3) (pid:2360) 
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> Commands Registered
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:18 (fd:3) (pid:2360) 
07/07 15:02:18 (fd:3) (pid:2360) 
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> Signals Registered
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> 1: SIGHUP handle_dc_sighup(), Blocked:0 Pending:0
07/07 15:02:18 (fd:3) (pid:2360) 
07/07 15:02:18 (fd:3) (pid:2360) 
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> Signals Registered
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> 1: SIGHUP handle_dc_sighup(), Blocked:0 Pending:0
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> 3: SIGQUIT handle_dc_sigquit(), Blocked:0 Pending:0
07/07 15:02:18 (fd:3) (pid:2360) 
07/07 15:02:18 (fd:3) (pid:2360) 
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> Signals Registered
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> 1: SIGHUP handle_dc_sighup(), Blocked:0 Pending:0
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> 3: SIGQUIT handle_dc_sigquit(), Blocked:0 Pending:0
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> 15: SIGTERM handle_dc_sigterm(), Blocked:0 Pending:0
07/07 15:02:18 (fd:3) (pid:2360) 
07/07 15:02:18 (fd:3) (pid:2360) 
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> Signals Registered
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> 1: SIGHUP handle_dc_sighup(), Blocked:0 Pending:0
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> 3: SIGQUIT handle_dc_sigquit(), Blocked:0 Pending:0
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> 15: SIGTERM handle_dc_sigterm(), Blocked:0 Pending:0
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> 60009: DC_SERVICEWAITPIDS HandleDC_SERVICEWAITPIDS(), Blocked:0 Pending:0
07/07 15:02:18 (fd:3) (pid:2360) 
07/07 15:02:18 (fd:3) (pid:2360) in DaemonCore NewTimer()
07/07 15:02:18 (fd:3) (pid:2360) 
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> Timers
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> ~~~~~~
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> id = 0, when = 1278514938, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:18 (fd:3) (pid:2360) 
07/07 15:02:18 (fd:3) (pid:2360) leaving DaemonCore NewTimer, id=0
07/07 15:02:18 (fd:3) (pid:2360) in DaemonCore NewTimer()
07/07 15:02:18 (fd:3) (pid:2360) 
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> Timers
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> ~~~~~~
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> id = 0, when = 1278514938, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:18 (fd:3) (pid:2360) DaemonCore--> id = 1, when = 1278514938, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:18 (fd:3) (pid:2360) 
07/07 15:02:18 (fd:3) (pid:2360) leaving DaemonCore NewTimer, id=1
07/07 15:02:19 (fd:3) (pid:2360) in DaemonCore NewTimer()
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> Timers
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> ~~~~~~
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 0, when = 1278514938, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 1, when = 1278514938, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 2, when = 1278514939, period = 300, handler_descrip=<check_session_cache>
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) leaving DaemonCore NewTimer, id=2
07/07 15:02:19 (fd:3) (pid:2360) SEC_DEFAULT_SESSION_DURATION is undefined, using default value of 3600
07/07 15:02:19 (fd:3) (pid:2360) in DaemonCore NewTimer()
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> Timers
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> ~~~~~~
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 0, when = 1278514938, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 1, when = 1278514938, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 2, when = 1278514939, period = 300, handler_descrip=<check_session_cache>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 3, when = 1278514939, period = 1801, handler_descrip=<handle_cookie_refresh>
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) leaving DaemonCore NewTimer, id=3
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60015: DC_OFF_PEACEFUL handle_off_peaceful()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60015: DC_OFF_PEACEFUL handle_off_peaceful()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60016: DC_SET_PEACEFUL_SHUTDOWN handle_set_peaceful_shutdown()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60011: DC_NOP handle_nop()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60015: DC_OFF_PEACEFUL handle_off_peaceful()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60016: DC_SET_PEACEFUL_SHUTDOWN handle_set_peaceful_shutdown()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60011: DC_NOP handle_nop()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60013: DC_FETCH_LOG handle_fetch_log()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60015: DC_OFF_PEACEFUL handle_off_peaceful()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60016: DC_SET_PEACEFUL_SHUTDOWN handle_set_peaceful_shutdown()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60011: DC_NOP handle_nop()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60013: DC_FETCH_LOG handle_fetch_log()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60015: DC_OFF_PEACEFUL handle_off_peaceful()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60016: DC_SET_PEACEFUL_SHUTDOWN handle_set_peaceful_shutdown()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60018: DC_PURGE_LOG handle_fetch_log_history_purge()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60011: DC_NOP handle_nop()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60013: DC_FETCH_LOG handle_fetch_log()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60014: DC_INVALIDATE_KEY handle_invalidate_key()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60015: DC_OFF_PEACEFUL handle_off_peaceful()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60016: DC_SET_PEACEFUL_SHUTDOWN handle_set_peaceful_shutdown()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60018: DC_PURGE_LOG handle_fetch_log_history_purge()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60011: DC_NOP handle_nop()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60013: DC_FETCH_LOG handle_fetch_log()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60014: DC_INVALIDATE_KEY handle_invalidate_key()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60015: DC_OFF_PEACEFUL handle_off_peaceful()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60016: DC_SET_PEACEFUL_SHUTDOWN handle_set_peaceful_shutdown()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60017: DC_TIME_OFFSET time_offset_cedar_stub
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60018: DC_PURGE_LOG handle_fetch_log_history_purge()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) DNS_CACHE_REFRESH is undefined, using default value of 28926
07/07 15:02:19 (fd:3) (pid:2360) in DaemonCore NewTimer()
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> Timers
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> ~~~~~~
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 0, when = 1278514938, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 1, when = 1278514938, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 2, when = 1278514939, period = 300, handler_descrip=<check_session_cache>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 3, when = 1278514939, period = 1801, handler_descrip=<handle_cookie_refresh>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 4, when = 1278543865, period = 28926, handler_descrip=<DaemonCore::refreshDNS()>
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) leaving DaemonCore NewTimer, id=4
07/07 15:02:19 (fd:3) (pid:2360) PIPE_BUFFER_MAX is undefined, using default value of 10240
07/07 15:02:19 (fd:3) (pid:2360) Config 'COLLECTOR_HOST': no prefix ==> '$(CONDOR_HOST)'
07/07 15:02:19 (fd:3) (pid:2360) COLLECTOR_HOST is set to "electrabel.pylos.logilab.fr"
07/07 15:02:19 (fd:3) (pid:2360) CREDD_TIMEOUT_MULTIPLIER is undefined, using default value of 0
07/07 15:02:19 (fd:3) (pid:2360) New Daemon obj (collector) name: "electrabel.pylos.logilab.fr", pool: "NULL", addr: "NULL"
07/07 15:02:19 (fd:3) (pid:2360) NONBLOCKING_COLLECTOR_UPDATE is undefined, using default value of True
07/07 15:02:19 (fd:3) (pid:2360) Using name "electrabel.pylos.logilab.fr" to find daemon
07/07 15:02:19 (fd:3) (pid:2360) Port not specified, using default (9618)
07/07 15:02:19 (fd:3) (pid:2360) Host info "electrabel.pylos.logilab.fr" is a hostname, finding IP address
07/07 15:02:19 (fd:3) (pid:2360) Trying to find IP addr for "electrabel.pylos.logilab.fr"
07/07 15:02:19 (fd:3) (pid:2360) Calling gethostbyname(electrabel.pylos.logilab.fr)
07/07 15:02:19 (fd:3) (pid:2360) Found IP addr in hostent: 172.17.7.223
07/07 15:02:19 (fd:3) (pid:2360) Found IP address and port <172.17.7.223:9618>
07/07 15:02:19 (fd:3) (pid:2360) DEAD_COLLECTOR_MAX_AVOIDANCE_TIME is undefined, using default value of 3600
07/07 15:02:19 (fd:3) (pid:2360) Will use UDP to update collector electrabel.pylos.logilab.fr <172.17.7.223:9618>
07/07 15:02:19 (fd:3) (pid:2360) SEC_INVALIDATE_SESSIONS_VIA_TCP is undefined, using default value of True
07/07 15:02:19 (fd:3) (pid:2360) ENABLE_SOAP is undefined, using default value of False
07/07 15:02:19 (fd:3) (pid:2360) ENABLE_WEB_SERVER is undefined, using default value of False
07/07 15:02:19 (fd:3) (pid:2360) FAKE_CREATE_THREAD is undefined, using default value of True
07/07 15:02:19 (fd:3) (pid:2360) CREDD_NOT_RESPONDING_TIMEOUT is undefined, using default value of -1
07/07 15:02:19 (fd:3) (pid:2360) NOT_RESPONDING_TIMEOUT is undefined, using default value of 0
07/07 15:02:19 (fd:3) (pid:2360) in DaemonCore NewTimer()
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> Timers
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> ~~~~~~
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 0, when = 1278514938, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 1, when = 1278514938, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 2, when = 1278514939, period = 300, handler_descrip=<check_session_cache>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 3, when = 1278514939, period = 1801, handler_descrip=<handle_cookie_refresh>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 5, when = 1278514939, period = 1170, handler_descrip=<DaemonCore::SendAliveToParent>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 4, when = 1278543865, period = 28926, handler_descrip=<DaemonCore::refreshDNS()>
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) leaving DaemonCore NewTimer, id=5
07/07 15:02:19 (fd:3) (pid:2360) QUILL_USE_SQL_LOG is undefined, using default value of False
07/07 15:02:19 (fd:3) (pid:2360) Config 'LOG': no prefix ==> '$(LOCAL_DIR)/log'
07/07 15:02:19 (fd:3) (pid:2360) WANT_XML_LOG is undefined, using default value of False
07/07 15:02:19 (fd:3) (pid:2360) main_init() called
07/07 15:02:19 (fd:3) (pid:2360) Config 'CREDD_HOST': no prefix ==> 'electrabel.pylos.logilab.fr'
07/07 15:02:19 (fd:3) (pid:2360) CREDD_UPDATE_INTERVAL is undefined, using default value of 300
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60011: DC_NOP handle_nop()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60013: DC_FETCH_LOG handle_fetch_log()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60014: DC_INVALIDATE_KEY handle_invalidate_key()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60015: DC_OFF_PEACEFUL handle_off_peaceful()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60016: DC_SET_PEACEFUL_SHUTDOWN handle_set_peaceful_shutdown()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60017: DC_TIME_OFFSET time_offset_cedar_stub
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60018: DC_PURGE_LOG handle_fetch_log_history_purge()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 479: STORE_CRED store_cred_handler
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 81099: CREDD_GET_PASSWD get_passwd_handler
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60011: DC_NOP handle_nop()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60013: DC_FETCH_LOG handle_fetch_log()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60014: DC_INVALIDATE_KEY handle_invalidate_key()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60015: DC_OFF_PEACEFUL handle_off_peaceful()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60016: DC_SET_PEACEFUL_SHUTDOWN handle_set_peaceful_shutdown()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60017: DC_TIME_OFFSET time_offset_cedar_stub
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60018: DC_PURGE_LOG handle_fetch_log_history_purge()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 479: STORE_CRED store_cred_handler
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> Commands Registered
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 81099: CREDD_GET_PASSWD get_passwd_handler
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 81100: CREDD_NOP nop_handler
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60000: DC_RAISESIGNAL HandleSigCommand()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60002: DC_CONFIG_PERSIST handle_config()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60003: DC_CONFIG_RUNTIME handle_config()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60004: DC_RECONFIG handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60005: DC_OFF_GRACEFUL handle_off_graceful()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60006: DC_OFF_FAST handle_off_fast()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60007: DC_CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60008: DC_CHILDALIVE HandleChildAliveCommand
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60011: DC_NOP handle_nop()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60012: DC_RECONFIG_FULL handle_reconfig()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60013: DC_FETCH_LOG handle_fetch_log()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60014: DC_INVALIDATE_KEY handle_invalidate_key()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60015: DC_OFF_PEACEFUL handle_off_peaceful()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60016: DC_SET_PEACEFUL_SHUTDOWN handle_set_peaceful_shutdown()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60017: DC_TIME_OFFSET time_offset_cedar_stub
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 60018: DC_PURGE_LOG handle_fetch_log_history_purge()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 457: CONFIG_VAL handle_config_val()
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> 479: STORE_CRED store_cred_handler
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) in DaemonCore NewTimer()
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> Timers
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> ~~~~~~
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 0, when = 1278514938, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 1, when = 1278514938, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 2, when = 1278514939, period = 300, handler_descrip=<check_session_cache>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 3, when = 1278514939, period = 1801, handler_descrip=<handle_cookie_refresh>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 5, when = 1278514939, period = 1170, handler_descrip=<DaemonCore::SendAliveToParent>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 6, when = 1278514939, period = 300, handler_descrip=<update_collector>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 4, when = 1278543865, period = 28926, handler_descrip=<DaemonCore::refreshDNS()>
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) leaving DaemonCore NewTimer, id=6
07/07 15:02:19 (fd:3) (pid:2360) selector 0012FA70 resetting
07/07 15:02:19 (fd:3) (pid:2360) ENABLE_STDOUT_TESTING is undefined, using default value of False
07/07 15:02:19 (fd:3) (pid:2360) In DaemonCore Timeout()
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> Timers
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> ~~~~~~
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 0, when = 1278514938, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 1, when = 1278514938, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 2, when = 1278514939, period = 300, handler_descrip=<check_session_cache>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 3, when = 1278514939, period = 1801, handler_descrip=<handle_cookie_refresh>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 5, when = 1278514939, period = 1170, handler_descrip=<DaemonCore::SendAliveToParent>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 6, when = 1278514939, period = 300, handler_descrip=<update_collector>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 4, when = 1278543865, period = 28926, handler_descrip=<DaemonCore::refreshDNS()>
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) Calling Timer handler 0 (dc_touch_log_file)
07/07 15:02:19 (fd:3) (pid:2360) TOUCH_LOG_INTERVAL is undefined, using default value of 60
07/07 15:02:19 (fd:3) (pid:2360) in DaemonCore NewTimer()
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> Timers
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> ~~~~~~
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 0, when = 1278514938, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 1, when = 1278514938, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 2, when = 1278514939, period = 300, handler_descrip=<check_session_cache>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 3, when = 1278514939, period = 1801, handler_descrip=<handle_cookie_refresh>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 5, when = 1278514939, period = 1170, handler_descrip=<DaemonCore::SendAliveToParent>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 6, when = 1278514939, period = 300, handler_descrip=<update_collector>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 7, when = 1278514999, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 4, when = 1278543865, period = 28926, handler_descrip=<DaemonCore::refreshDNS()>
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) leaving DaemonCore NewTimer, id=7
07/07 15:02:19 (fd:3) (pid:2360) Return from Timer handler 0 (dc_touch_log_file)
07/07 15:02:19 (fd:3) (pid:2360) PRIV_CONDOR --> PRIV_CONDOR at ..\src\condor_daemon_core.V6\daemon_core.cpp:3548
07/07 15:02:19 (fd:3) (pid:2360) Calling Timer handler 1 (dc_touch_lock_files)
07/07 15:02:19 (fd:3) (pid:2360) PRIV_CONDOR --> PRIV_CONDOR at ..\src\condor_daemon_core.V6\daemon_core_main.cpp:549
07/07 15:02:19 (fd:3) (pid:2360) PRIV_CONDOR --> PRIV_CONDOR at ..\src\condor_daemon_core.V6\daemon_core_main.cpp:553
07/07 15:02:19 (fd:3) (pid:2360) LOCK_FILE_UPDATE_INTERVAL is undefined, using default value of 28800
07/07 15:02:19 (fd:3) (pid:2360) in DaemonCore NewTimer()
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> Timers
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> ~~~~~~
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 1, when = 1278514938, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 2, when = 1278514939, period = 300, handler_descrip=<check_session_cache>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 3, when = 1278514939, period = 1801, handler_descrip=<handle_cookie_refresh>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 5, when = 1278514939, period = 1170, handler_descrip=<DaemonCore::SendAliveToParent>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 6, when = 1278514939, period = 300, handler_descrip=<update_collector>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 7, when = 1278514999, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 8, when = 1278543739, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 4, when = 1278543865, period = 28926, handler_descrip=<DaemonCore::refreshDNS()>
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) leaving DaemonCore NewTimer, id=8
07/07 15:02:19 (fd:3) (pid:2360) Return from Timer handler 1 (dc_touch_lock_files)
07/07 15:02:19 (fd:3) (pid:2360) PRIV_CONDOR --> PRIV_CONDOR at ..\src\condor_daemon_core.V6\daemon_core.cpp:3548
07/07 15:02:19 (fd:3) (pid:2360) Calling Timer handler 2 (check_session_cache)
07/07 15:02:19 (fd:3) (pid:2360) Return from Timer handler 2 (check_session_cache)
07/07 15:02:19 (fd:3) (pid:2360) PRIV_CONDOR --> PRIV_CONDOR at ..\src\condor_daemon_core.V6\daemon_core.cpp:3548
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore Timeout() Complete, returning 0 
07/07 15:02:19 (fd:3) (pid:2360) selector 0012FA70 resetting
07/07 15:02:19 (fd:3) (pid:2360) selector 0012FA70 adding fd 1772 ()
07/07 15:02:19 (fd:3) (pid:2360) selector 0012FA70 adding fd 1768 ()
07/07 15:02:19 (fd:3) (pid:2360) selector 0012FA70 adding fd 1788 ()
07/07 15:02:19 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:19 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:19 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:19 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:19 (fd:3) (pid:2360) In DaemonCore Timeout()
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> Timers
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> ~~~~~~
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 3, when = 1278514939, period = 1801, handler_descrip=<handle_cookie_refresh>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 5, when = 1278514939, period = 1170, handler_descrip=<DaemonCore::SendAliveToParent>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 6, when = 1278514939, period = 300, handler_descrip=<update_collector>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 7, when = 1278514999, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 2, when = 1278515239, period = 300, handler_descrip=<check_session_cache>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 8, when = 1278543739, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore--> id = 4, when = 1278543865, period = 28926, handler_descrip=<DaemonCore::refreshDNS()>
07/07 15:02:19 (fd:3) (pid:2360) 
07/07 15:02:19 (fd:3) (pid:2360) Calling Timer handler 3 (handle_cookie_refresh)
07/07 15:02:19 (fd:3) (pid:2360) Return from Timer handler 3 (handle_cookie_refresh)
07/07 15:02:19 (fd:3) (pid:2360) PRIV_CONDOR --> PRIV_CONDOR at ..\src\condor_daemon_core.V6\daemon_core.cpp:3548
07/07 15:02:19 (fd:3) (pid:2360) Calling Timer handler 5 (DaemonCore::SendAliveToParent)
07/07 15:02:19 (fd:3) (pid:2360) DaemonCore: in SendAliveToParent()
07/07 15:02:19 (fd:3) (pid:2360) CREDD_TIMEOUT_MULTIPLIER is undefined, using default value of 0
07/07 15:02:19 (fd:3) (pid:2360) New Daemon obj (any) name: "NULL", pool: "NULL", addr: "<172.17.7.223:4398>"
07/07 15:02:19 (fd:3) (pid:2360) SECMAN: command 60008 DC_CHILDALIVE to <172.17.7.223:4398> from UDP port 4411 (blocking).
07/07 15:02:19 (fd:3) (pid:2360) Config 'SEC_DEFAULT_AUTHENTICATION': using prefix 'credd.' ==> 'REQUIRED'
07/07 15:02:19 (fd:3) (pid:2360) Config 'SEC_DEFAULT_ENCRYPTION': using prefix 'credd.' ==> 'REQUIRED'
07/07 15:02:19 (fd:3) (pid:2360) Config 'SEC_DEFAULT_INTEGRITY': using prefix 'credd.' ==> 'REQUIRED'
07/07 15:02:19 (fd:3) (pid:2360) Config 'SEC_DEFAULT_NEGOTIATION': using prefix 'credd.' ==> 'REQUIRED'
07/07 15:02:19 (fd:3) (pid:2360) Config 'SEC_DEFAULT_AUTHENTICATION_METHODS': using prefix 'credd.' ==> 'NTSSPI'
07/07 15:02:19 (fd:3) (pid:2360) SECMAN: no cached key for {<172.17.7.223:4398>,<60008>}.
07/07 15:02:19 (fd:3) (pid:2360) SECMAN: Security Policy:
MyType = ""
TargetType = ""
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
OutgoingNegotiation = "REQUIRED"
Authentication = "REQUIRED"
Encryption = "REQUIRED"
Integrity = "REQUIRED"
Enact = "NO"
Subsystem = "CREDD"
ParentUniqueID = "electrabel:2064:1278514935"
ServerPid = 2360
SessionDuration = "86400"
07/07 15:02:19 (fd:3) (pid:2360) SECMAN: negotiating security for command 60008.
07/07 15:02:19 (fd:3) (pid:2360) SECMAN: need to start a session via TCP
07/07 15:02:19 (fd:3) (pid:2360) SEC_TCP_SESSION_TIMEOUT is undefined, using default value of 20
07/07 15:02:19 (fd:3) (pid:2360) selector 0012082C resetting
07/07 15:02:19 (fd:3) (pid:2360) selector 0012082C resetting
07/07 15:02:19 (fd:3) (pid:2360) selector 0012082C adding fd 1744 ()
07/07 15:02:19 (fd:3) (pid:2360) selector 0012082C adding fd 1744 ()
07/07 15:02:19 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:19 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:19 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:19 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:19 (fd:3) (pid:2360) CONNECT src=<172.17.7.223:4412> fd=1744 dst=<172.17.7.223:4398>
07/07 15:02:19 (fd:3) (pid:2360) SECMAN: command 60010 DC_CHILDALIVE to <172.17.7.223:4398> from TCP port 4412 (blocking).
07/07 15:02:19 (fd:3) (pid:2360) Config 'SEC_DEFAULT_AUTHENTICATION': using prefix 'credd.' ==> 'REQUIRED'
07/07 15:02:19 (fd:3) (pid:2360) Config 'SEC_DEFAULT_ENCRYPTION': using prefix 'credd.' ==> 'REQUIRED'
07/07 15:02:19 (fd:3) (pid:2360) Config 'SEC_DEFAULT_INTEGRITY': using prefix 'credd.' ==> 'REQUIRED'
07/07 15:02:19 (fd:3) (pid:2360) Config 'SEC_DEFAULT_NEGOTIATION': using prefix 'credd.' ==> 'REQUIRED'
07/07 15:02:19 (fd:3) (pid:2360) Config 'SEC_DEFAULT_AUTHENTICATION_METHODS': using prefix 'credd.' ==> 'NTSSPI'
07/07 15:02:19 (fd:3) (pid:2360) SECMAN: no cached key for {<172.17.7.223:4398>,<60010>}.
07/07 15:02:19 (fd:3) (pid:2360) SECMAN: Security Policy:
MyType = ""
TargetType = ""
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
OutgoingNegotiation = "REQUIRED"
Authentication = "REQUIRED"
Encryption = "REQUIRED"
Integrity = "REQUIRED"
Enact = "NO"
Subsystem = "CREDD"
ParentUniqueID = "electrabel:2064:1278514935"
ServerPid = 2360
SessionDuration = "86400"
NewSession = "YES"
07/07 15:02:19 (fd:3) (pid:2360) SECMAN: negotiating security for command 60010.
07/07 15:02:19 (fd:3) (pid:2360) SECMAN: sending DC_AUTHENTICATE command
07/07 15:02:19 (fd:3) (pid:2360) SECMAN: sending following classad:
MyType = ""
TargetType = ""
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
OutgoingNegotiation = "REQUIRED"
Authentication = "REQUIRED"
Encryption = "REQUIRED"
Integrity = "REQUIRED"
Enact = "NO"
Subsystem = "CREDD"
ParentUniqueID = "electrabel:2064:1278514935"
ServerPid = 2360
SessionDuration = "86400"
NewSession = "YES"
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
ServerCommandSock = "<172.17.7.223:9620>"
Command = 60010
AuthCommand = 60008
07/07 15:02:19 (fd:3) (pid:2360) selector 00120588 resetting
07/07 15:02:19 (fd:3) (pid:2360) condor_write(fd=1744 <172.17.7.223:4398>,,size=503,timeout=20,flags=0)
07/07 15:02:19 (fd:3) (pid:2360) selector 00120588 adding fd 1744 ()
07/07 15:02:19 (fd:3) (pid:2360) selector 00120588 adding fd 1744 ()
07/07 15:02:19 (fd:3) (pid:2360) selector 00120588 adding fd 1744 ()
07/07 15:02:19 (fd:3) (pid:2360) selector 00120588 adding fd 1744 ()
07/07 15:02:19 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:19 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:19 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:19 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:19 (fd:3) (pid:2360) Entering thread safe start [send] in condor_rw.cpp:357 924()
07/07 15:02:19 (fd:3) (pid:2360) Leaving thread safe start [send] in condor_rw.cpp:357 924()
07/07 15:02:19 (fd:3) (pid:2360) Entering thread safe stop [send] in condor_rw.cpp:359 924()
07/07 15:02:19 (fd:3) (pid:2360) Leaving thread safe stop [send] in condor_rw.cpp:359 924()
07/07 15:02:19 (fd:3) (pid:2360) selector 0012060C resetting
07/07 15:02:19 (fd:3) (pid:2360) condor_read(fd=1744 <172.17.7.223:4398>,,size=5,timeout=20,flags=0)
07/07 15:02:19 (fd:3) (pid:2360) selector 0012060C adding fd 1744 ()
07/07 15:02:19 (fd:3) (pid:2360) condor_read(): fd=1744
07/07 15:02:19 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:19 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:20 (fd:3) (pid:2360) selector 001205F0 resetting
07/07 15:02:20 (fd:3) (pid:2360) condor_read(fd=1744 <172.17.7.223:4398>,,size=290,timeout=20,flags=0)
07/07 15:02:20 (fd:3) (pid:2360) selector 001205F0 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) condor_read(): fd=1744
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: server responded with:
MyType = "(unknown type)"
TargetType = "(unknown type)"
Authentication = "YES"
Encryption = "YES"
Integrity = "YES"
AuthMethodsList = "NTSSPI"
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
SessionDuration = "86400"
Enact = "YES"
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: new session, doing initial authentication.
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: authenticating RIGHT NOW.
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: AuthMethodsList: NTSSPI
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: Auth methods: NTSSPI
07/07 15:02:20 (fd:3) (pid:2360) AUTHENTICATE: in authenticate( addr == '<172.17.7.223:4398>', methods == 'NTSSPI')
07/07 15:02:20 (fd:3) (pid:2360) AUTHENTICATE: can still try these methods: NTSSPI
07/07 15:02:20 (fd:3) (pid:2360) HANDSHAKE: in handshake(my_methods = 'NTSSPI')
07/07 15:02:20 (fd:3) (pid:2360) HANDSHAKE: handshake() - i am the client
07/07 15:02:20 (fd:3) (pid:2360) HANDSHAKE: sending (methods == 16) to server
07/07 15:02:20 (fd:3) (pid:2360) selector 00120554 resetting
07/07 15:02:20 (fd:3) (pid:2360) condor_write(fd=1744 <172.17.7.223:4398>,,size=13,timeout=20,flags=0)
07/07 15:02:20 (fd:3) (pid:2360) selector 00120554 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) selector 00120554 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) selector 00120554 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) selector 00120554 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [send] in condor_rw.cpp:357 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [send] in condor_rw.cpp:357 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [send] in condor_rw.cpp:359 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [send] in condor_rw.cpp:359 924()
07/07 15:02:20 (fd:3) (pid:2360) selector 00120550 resetting
07/07 15:02:20 (fd:3) (pid:2360) condor_read(fd=1744 <172.17.7.223:4398>,,size=5,timeout=20,flags=0)
07/07 15:02:20 (fd:3) (pid:2360) selector 00120550 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) condor_read(): fd=1744
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:20 (fd:3) (pid:2360) selector 00120534 resetting
07/07 15:02:20 (fd:3) (pid:2360) condor_read(fd=1744 <172.17.7.223:4398>,,size=8,timeout=20,flags=0)
07/07 15:02:20 (fd:3) (pid:2360) selector 00120534 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) condor_read(): fd=1744
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:20 (fd:3) (pid:2360) HANDSHAKE: server replied (method = 16)
07/07 15:02:20 (fd:3) (pid:2360) Config 'UID_DOMAIN': no prefix ==> 'pylos.logilab.fr'
07/07 15:02:20 (fd:3) (pid:2360) AUTHENTICATE: will try to use 16 (NTSSPI)
07/07 15:02:20 (fd:3) (pid:2360) sspi_client_auth() entered
07/07 15:02:20 (fd:3) (pid:2360) selector 00120510 resetting
07/07 15:02:20 (fd:3) (pid:2360) condor_write(fd=1744 <172.17.7.223:4398>,,size=13,timeout=20,flags=0)
07/07 15:02:20 (fd:3) (pid:2360) selector 00120510 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) selector 00120510 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) selector 00120510 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) selector 00120510 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [send] in condor_rw.cpp:357 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [send] in condor_rw.cpp:357 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [send] in condor_rw.cpp:359 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [send] in condor_rw.cpp:359 924()
07/07 15:02:20 (fd:3) (pid:2360) selector 00120510 resetting
07/07 15:02:20 (fd:3) (pid:2360) condor_write(fd=1744 <172.17.7.223:4398>,,size=60,timeout=20,flags=0)
07/07 15:02:20 (fd:3) (pid:2360) selector 00120510 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) selector 00120510 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) selector 00120510 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) selector 00120510 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [send] in condor_rw.cpp:357 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [send] in condor_rw.cpp:357 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [send] in condor_rw.cpp:359 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [send] in condor_rw.cpp:359 924()
07/07 15:02:20 (fd:3) (pid:2360) selector 001204FC resetting
07/07 15:02:20 (fd:3) (pid:2360) condor_read(fd=1744 <172.17.7.223:4398>,,size=5,timeout=20,flags=0)
07/07 15:02:20 (fd:3) (pid:2360) selector 001204FC adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) condor_read(): fd=1744
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:20 (fd:3) (pid:2360) selector 001204E0 resetting
07/07 15:02:20 (fd:3) (pid:2360) condor_read(fd=1744 <172.17.7.223:4398>,,size=8,timeout=20,flags=0)
07/07 15:02:20 (fd:3) (pid:2360) selector 001204E0 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) condor_read(): fd=1744
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:20 (fd:3) (pid:2360) selector 00120528 resetting
07/07 15:02:20 (fd:3) (pid:2360) condor_read(fd=1744 <172.17.7.223:4398>,,size=5,timeout=20,flags=0)
07/07 15:02:20 (fd:3) (pid:2360) selector 00120528 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) condor_read(): fd=1744
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:20 (fd:3) (pid:2360) selector 0012050C resetting
07/07 15:02:20 (fd:3) (pid:2360) condor_read(fd=1744 <172.17.7.223:4398>,,size=238,timeout=20,flags=0)
07/07 15:02:20 (fd:3) (pid:2360) selector 0012050C adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) condor_read(): fd=1744
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:20 (fd:3) (pid:2360) sspi_client_auth() looping
07/07 15:02:20 (fd:3) (pid:2360) selector 00120510 resetting
07/07 15:02:20 (fd:3) (pid:2360) condor_write(fd=1744 <172.17.7.223:4398>,,size=13,timeout=20,flags=0)
07/07 15:02:20 (fd:3) (pid:2360) selector 00120510 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) selector 00120510 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) selector 00120510 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) selector 00120510 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [send] in condor_rw.cpp:357 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [send] in condor_rw.cpp:357 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [send] in condor_rw.cpp:359 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [send] in condor_rw.cpp:359 924()
07/07 15:02:20 (fd:3) (pid:2360) selector 00120510 resetting
07/07 15:02:20 (fd:3) (pid:2360) condor_write(fd=1744 <172.17.7.223:4398>,,size=77,timeout=20,flags=0)
07/07 15:02:20 (fd:3) (pid:2360) selector 00120510 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) selector 00120510 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) selector 00120510 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) selector 00120510 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [send] in condor_rw.cpp:357 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [send] in condor_rw.cpp:357 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [send] in condor_rw.cpp:359 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [send] in condor_rw.cpp:359 924()
07/07 15:02:20 (fd:3) (pid:2360) sspi_client_auth() exiting
07/07 15:02:20 (fd:3) (pid:2360) AUTHENTICATE: auth_status == 16 (NTSSPI)
07/07 15:02:20 (fd:3) (pid:2360) Authentication was a Success.
07/07 15:02:20 (fd:3) (pid:2360) ZKM: setting default map to (null)
07/07 15:02:20 (fd:3) (pid:2360) ZKM: post-map: current user is '(null)'
07/07 15:02:20 (fd:3) (pid:2360) ZKM: post-map: current domain is '(null)'
07/07 15:02:20 (fd:3) (pid:2360) ZKM: post-map: current FQU is '(null)'
07/07 15:02:20 (fd:3) (pid:2360) selector 001205F4 resetting
07/07 15:02:20 (fd:3) (pid:2360) condor_read(fd=1744 <172.17.7.223:4398>,,size=5,timeout=20,flags=0)
07/07 15:02:20 (fd:3) (pid:2360) selector 001205F4 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) condor_read(): fd=1744
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:20 (fd:3) (pid:2360) selector 001205D8 resetting
07/07 15:02:20 (fd:3) (pid:2360) condor_read(fd=1744 <172.17.7.223:4398>,,size=8,timeout=20,flags=0)
07/07 15:02:20 (fd:3) (pid:2360) selector 001205D8 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) condor_read(): fd=1744
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:20 (fd:3) (pid:2360) selector 001205F4 resetting
07/07 15:02:20 (fd:3) (pid:2360) condor_read(fd=1744 <172.17.7.223:4398>,,size=5,timeout=20,flags=0)
07/07 15:02:20 (fd:3) (pid:2360) selector 001205F4 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) condor_read(): fd=1744
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:20 (fd:3) (pid:2360) selector 001205D8 resetting
07/07 15:02:20 (fd:3) (pid:2360) condor_read(fd=1744 <172.17.7.223:4398>,,size=72,timeout=20,flags=0)
07/07 15:02:20 (fd:3) (pid:2360) selector 001205D8 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) condor_read(): fd=1744
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: about to enable message authenticator.
07/07 15:02:20 (fd:3) (pid:2360) SEC_DEBUG_PRINT_KEYS is undefined, using default value of False
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: successfully enabled message authenticator!
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: about to enable encryption.
07/07 15:02:20 (fd:3) (pid:2360) SEC_DEBUG_PRINT_KEYS is undefined, using default value of False
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: successfully enabled encryption!
07/07 15:02:20 (fd:3) (pid:2360) selector 001205D8 resetting
07/07 15:02:20 (fd:3) (pid:2360) condor_read(fd=1744 <172.17.7.223:4398>,,size=21,timeout=20,flags=0)
07/07 15:02:20 (fd:3) (pid:2360) selector 001205D8 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) condor_read(): fd=1744
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:20 (fd:3) (pid:2360) selector 001205BC resetting
07/07 15:02:20 (fd:3) (pid:2360) condor_read(fd=1744 <172.17.7.223:4398>,,size=231,timeout=20,flags=0)
07/07 15:02:20 (fd:3) (pid:2360) selector 001205BC adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) condor_read(): fd=1744
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: received post-auth classad:
MyType = "(unknown type)"
TargetType = "(unknown type)"
User = "SYSTEM@nt authority"
TriedAuthentication = TRUE
Sid = "electrabel:2064:1278514939:1"
ValidCommands = "60000,60008,60017,60004,60007,457"
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: policy to be cached:
MyType = ""
TargetType = ""
OutgoingNegotiation = "REQUIRED"
Subsystem = "CREDD"
Command = 60010
AuthCommand = 60008
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
Enact = "YES"
AuthMethodsList = "NTSSPI"
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
Authentication = "YES"
Encryption = "YES"
Integrity = "YES"
SessionDuration = "86400"
UseSession = "YES"
Sid = "electrabel:2064:1278514939:1"
MyRemoteUserName = "SYSTEM@nt authority"
ValidCommands = "60000,60008,60017,60004,60007,457"
TriedAuthentication = TRUE
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: added session electrabel:2064:1278514939:1 to cache for 86400 seconds.
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:4398>,<60000>} mapped to session electrabel:2064:1278514939:1.
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:4398>,<60008>} mapped to session electrabel:2064:1278514939:1.
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:4398>,<60017>} mapped to session electrabel:2064:1278514939:1.
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:4398>,<60004>} mapped to session electrabel:2064:1278514939:1.
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:4398>,<60007>} mapped to session electrabel:2064:1278514939:1.
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:4398>,<457>} mapped to session electrabel:2064:1278514939:1.
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: startCommand succeeded.
07/07 15:02:20 (fd:3) (pid:2360) Authorizing server '*/172.17.7.223'.
07/07 15:02:20 (fd:3) (pid:2360) Config 'ALLOW_READ': no prefix ==> '*/electrabel.pylos.logilab.fr, */electrabel2.pylos.logilab.fr'
07/07 15:02:20 (fd:3) (pid:2360) IPVERIFY: allow READ: */electrabel.pylos.logilab.fr, */electrabel2.pylos.logilab.fr (from config value ALLOW_READ)
07/07 15:02:20 (fd:3) (pid:2360) Config 'ALLOW_WRITE': no prefix ==> '*/electrabel.pylos.logilab.fr, */electrabel2.pylos.logilab.fr'
07/07 15:02:20 (fd:3) (pid:2360) IPVERIFY: allow WRITE: */electrabel.pylos.logilab.fr, */electrabel2.pylos.logilab.fr (from config value ALLOW_WRITE)
07/07 15:02:20 (fd:3) (pid:2360) Config 'ALLOW_NEGOTIATOR': no prefix ==> '$(CONDOR_HOST)'
07/07 15:02:20 (fd:3) (pid:2360) IPVERIFY: allow NEGOTIATOR: electrabel.pylos.logilab.fr (from config value ALLOW_NEGOTIATOR)
07/07 15:02:20 (fd:3) (pid:2360) Config 'ALLOW_ADMINISTRATOR': no prefix ==> '*/electrabel.pylos.logilab.fr, */electrabel2.pylos.logilab.fr'
07/07 15:02:20 (fd:3) (pid:2360) IPVERIFY: allow ADMINISTRATOR: */electrabel.pylos.logilab.fr, */electrabel2.pylos.logilab.fr (from config value ALLOW_ADMINISTRATOR)
07/07 15:02:20 (fd:3) (pid:2360) Config 'ALLOW_OWNER': no prefix ==> '$(FULL_HOSTNAME), $(ALLOW_ADMINISTRATOR)'
07/07 15:02:20 (fd:3) (pid:2360) IPVERIFY: allow OWNER: electrabel.pylos.logilab.fr, */electrabel.pylos.logilab.fr, */electrabel2.pylos.logilab.fr (from config value ALLOW_OWNER)
07/07 15:02:20 (fd:3) (pid:2360) Config 'ALLOW_CONFIG': no prefix ==> '*/electrabel.pylos.logilab.fr, */electrabel2.pylos.logilab.fr'
07/07 15:02:20 (fd:3) (pid:2360) IPVERIFY: allow CONFIG: */electrabel.pylos.logilab.fr, */electrabel2.pylos.logilab.fr (from config value ALLOW_CONFIG)
07/07 15:02:20 (fd:3) (pid:2360) Config 'ALLOW_DAEMON': using prefix 'credd.' ==> 'condor_pool@$(UID_DOMAIN)'
07/07 15:02:20 (fd:3) (pid:2360) IPVERIFY: allow DAEMON: condor_pool@pylos.logilab.fr (from config value ALLOW_DAEMON)
07/07 15:02:20 (fd:3) (pid:2360) ipverify: SOAP optimized to allow anyone
07/07 15:02:20 (fd:3) (pid:2360) ipverify: DEFAULT optimized to allow anyone
07/07 15:02:20 (fd:3) (pid:2360) ipverify: CLIENT optimized to allow anyone
07/07 15:02:20 (fd:3) (pid:2360) Config 'ALLOW_DAEMON': using prefix 'credd.' ==> 'condor_pool@$(UID_DOMAIN)'
07/07 15:02:20 (fd:3) (pid:2360) IPVERIFY: allow ADVERTISE_STARTD: condor_pool@pylos.logilab.fr (from config value ALLOW_DAEMON)
07/07 15:02:20 (fd:3) (pid:2360) Config 'ALLOW_DAEMON': using prefix 'credd.' ==> 'condor_pool@$(UID_DOMAIN)'
07/07 15:02:20 (fd:3) (pid:2360) IPVERIFY: allow ADVERTISE_SCHEDD: condor_pool@pylos.logilab.fr (from config value ALLOW_DAEMON)
07/07 15:02:20 (fd:3) (pid:2360) Config 'ALLOW_DAEMON': using prefix 'credd.' ==> 'condor_pool@$(UID_DOMAIN)'
07/07 15:02:20 (fd:3) (pid:2360) IPVERIFY: allow ADVERTISE_MASTER: condor_pool@pylos.logilab.fr (from config value ALLOW_DAEMON)
07/07 15:02:20 (fd:3) (pid:2360) Initialized the following authorization table:
07/07 15:02:20 (fd:3) (pid:2360) Authorizations yet to be resolved:
07/07 15:02:20 (fd:3) (pid:2360) allow READ:  */172.17.7.223 */172.17.7.224 */electrabel2.pylos.logilab.fr */electrabel.pylos.logilab.fr
07/07 15:02:20 (fd:3) (pid:2360) allow WRITE:  */172.17.7.223 */172.17.7.224 */electrabel2.pylos.logilab.fr */electrabel.pylos.logilab.fr
07/07 15:02:20 (fd:3) (pid:2360) allow NEGOTIATOR:  */172.17.7.223 */electrabel.pylos.logilab.fr
07/07 15:02:20 (fd:3) (pid:2360) allow ADMINISTRATOR:  */172.17.7.223 */172.17.7.224 */electrabel2.pylos.logilab.fr */electrabel.pylos.logilab.fr
07/07 15:02:20 (fd:3) (pid:2360) 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:20 (fd:3) (pid:2360) allow CONFIG:  */172.17.7.223 */172.17.7.224 */electrabel2.pylos.logilab.fr */electrabel.pylos.logilab.fr
07/07 15:02:20 (fd:3) (pid:2360) allow DAEMON:  condor_pool@pylos.logilab.fr/*
07/07 15:02:20 (fd:3) (pid:2360) allow ADVERTISE_STARTD:  condor_pool@pylos.logilab.fr/*
07/07 15:02:20 (fd:3) (pid:2360) allow ADVERTISE_SCHEDD:  condor_pool@pylos.logilab.fr/*
07/07 15:02:20 (fd:3) (pid:2360) allow ADVERTISE_MASTER:  condor_pool@pylos.logilab.fr/*
07/07 15:02:20 (fd:3) (pid:2360) CLOSE <172.17.7.223:4412> fd=1744
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: succesfully created security session to <172.17.7.223:4398> via TCP!
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: resuming command 60008 DC_CHILDALIVE to <172.17.7.223:4398> from UDP port 4411 (blocking).
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: using session electrabel:2064:1278514939:1 for {<172.17.7.223:4398>,<60008>}.
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: found cached session id electrabel:2064:1278514939:1 for {<172.17.7.223:4398>,<60008>}.
07/07 15:02:20 (fd:3) (pid:2360) SEC_DEBUG_PRINT_KEYS is undefined, using default value of False
MyType = ""
TargetType = ""
ParentUniqueID = "electrabel:2064:1278514935"
ServerPid = 2360
OutgoingNegotiation = "REQUIRED"
Subsystem = "CREDD"
Command = 60010
AuthCommand = 60008
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
Enact = "YES"
AuthMethodsList = "NTSSPI"
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
Authentication = "YES"
Encryption = "YES"
Integrity = "YES"
SessionDuration = "86400"
UseSession = "YES"
Sid = "electrabel:2064:1278514939:1"
MyRemoteUserName = "SYSTEM@nt authority"
ValidCommands = "60000,60008,60017,60004,60007,457"
TriedAuthentication = TRUE
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: Security Policy:
MyType = ""
TargetType = ""
ParentUniqueID = "electrabel:2064:1278514935"
ServerPid = 2360
OutgoingNegotiation = "REQUIRED"
Subsystem = "CREDD"
Command = 60010
AuthCommand = 60008
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
Enact = "YES"
AuthMethodsList = "NTSSPI"
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
Authentication = "YES"
Encryption = "YES"
Integrity = "YES"
SessionDuration = "86400"
UseSession = "YES"
Sid = "electrabel:2064:1278514939:1"
MyRemoteUserName = "SYSTEM@nt authority"
ValidCommands = "60000,60008,60017,60004,60007,457"
TriedAuthentication = TRUE
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: negotiating security for command 60008.
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: UDP, m_have_session == 1, peer_can_negotiate == 1
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: UDP has session electrabel:2064:1278514939:1.
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: about to enable message authenticator.
07/07 15:02:20 (fd:3) (pid:2360) SEC_DEBUG_PRINT_KEYS is undefined, using default value of False
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: successfully enabled message authenticator!
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: about to enable encryption.
07/07 15:02:20 (fd:3) (pid:2360) SEC_DEBUG_PRINT_KEYS is undefined, using default value of False
07/07 15:02:20 (fd:3) (pid:2360) set_encryption_id: setting key length 48
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: successfully enabled encryption.
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: sending DC_AUTHENTICATE command
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: sending following classad:
MyType = ""
TargetType = ""
ParentUniqueID = "electrabel:2064:1278514935"
ServerPid = 2360
OutgoingNegotiation = "REQUIRED"
Subsystem = "CREDD"
AuthCommand = 60008
Enact = "YES"
AuthMethodsList = "NTSSPI"
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
Authentication = "YES"
Encryption = "YES"
Integrity = "YES"
SessionDuration = "86400"
UseSession = "YES"
Sid = "electrabel:2064:1278514939:1"
MyRemoteUserName = "SYSTEM@nt authority"
ValidCommands = "60000,60008,60017,60004,60007,457"
TriedAuthentication = TRUE
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
ServerCommandSock = "<172.17.7.223:9620>"
Command = 60008
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: startCommand succeeded.
07/07 15:02:20 (fd:3) (pid:2360) Authorizing server '*/172.17.7.223'.
07/07 15:02:20 (fd:3) (pid:2360) Destroying Daemon object:
07/07 15:02:20 (fd:3) (pid:2360) Type: 1 (any), Name: (null), Addr: <172.17.7.223:4398>
07/07 15:02:20 (fd:3) (pid:2360) FullHost: (null), Host: (null), Pool: (null), Port: -1
07/07 15:02:20 (fd:3) (pid:2360) IsLocal: N, IdStr: (null), Error: (null)
07/07 15:02:20 (fd:3) (pid:2360)  --- End of Daemon object info ---
07/07 15:02:20 (fd:3) (pid:2360) SEND [1000] <172.17.7.223:4411> <172.17.7.223:4398>
07/07 15:02:20 (fd:3) (pid:2360) SEND [40] <172.17.7.223:4411> <172.17.7.223:4398>
07/07 15:02:20 (fd:3) (pid:2360) DaemonCore: Leaving SendAliveToParent() - success
07/07 15:02:20 (fd:3) (pid:2360) Return from Timer handler 5 (DaemonCore::SendAliveToParent)
07/07 15:02:20 (fd:3) (pid:2360) PRIV_CONDOR --> PRIV_CONDOR at ..\src\condor_daemon_core.V6\daemon_core.cpp:3548
07/07 15:02:20 (fd:3) (pid:2360) Calling Timer handler 6 (update_collector)
07/07 15:02:20 (fd:3) (pid:2360) Trying to update collector <172.17.7.223:9618>
07/07 15:02:20 (fd:3) (pid:2360) Attempting to send update via UDP to collector electrabel.pylos.logilab.fr <172.17.7.223:9618>
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: command 58 UPDATE_AD_GENERIC to collector electrabel.pylos.logilab.fr from UDP port 4418 (non-blocking).
07/07 15:02:20 (fd:3) (pid:2360) Config 'SEC_DEFAULT_AUTHENTICATION': using prefix 'credd.' ==> 'REQUIRED'
07/07 15:02:20 (fd:3) (pid:2360) Config 'SEC_DEFAULT_ENCRYPTION': using prefix 'credd.' ==> 'REQUIRED'
07/07 15:02:20 (fd:3) (pid:2360) Config 'SEC_DEFAULT_INTEGRITY': using prefix 'credd.' ==> 'REQUIRED'
07/07 15:02:20 (fd:3) (pid:2360) Config 'SEC_DEFAULT_NEGOTIATION': using prefix 'credd.' ==> 'REQUIRED'
07/07 15:02:20 (fd:3) (pid:2360) Config 'SEC_DEFAULT_AUTHENTICATION_METHODS': using prefix 'credd.' ==> 'NTSSPI'
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: no cached key for {<172.17.7.223:9618>,<58>}.
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: Security Policy:
MyType = ""
TargetType = ""
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
OutgoingNegotiation = "REQUIRED"
Authentication = "REQUIRED"
Encryption = "REQUIRED"
Integrity = "REQUIRED"
Enact = "NO"
Subsystem = "CREDD"
ParentUniqueID = "electrabel:2064:1278514935"
ServerPid = 2360
SessionDuration = "86400"
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: negotiating security for command 58.
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: need to start a session via TCP
07/07 15:02:20 (fd:3) (pid:2360) SEC_TCP_SESSION_TIMEOUT is undefined, using default value of 20
07/07 15:02:20 (fd:3) (pid:2360) non-blocking CONNECT started fd=1744 dst=<172.17.7.223:9618>
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: command 60010 UPDATE_AD_GENERIC to <172.17.7.223:9618> from TCP port 4419 (non-blocking).
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: waiting for TCP connection to <172.17.7.223:9618>.
07/07 15:02:20 (fd:3) (pid:2360) NETWORK_MAX_PENDING_CONNECTS is undefined, using default value of 0
07/07 15:02:20 (fd:3) (pid:2360) File descriptor limits: max 1024, safe 820
07/07 15:02:20 (fd:3) (pid:2360) 
07/07 15:02:20 (fd:3) (pid:2360) DaemonCore--> Sockets Registered
07/07 15:02:20 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:20 (fd:3) (pid:2360) DaemonCore--> 0: 1772 <NULL> DC Command Handler
07/07 15:02:20 (fd:3) (pid:2360) DaemonCore--> 1: 1768 <NULL> DC Command Handler
07/07 15:02:20 (fd:3) (pid:2360) DaemonCore--> 2: 1744 <172.17.7.223:9618> SecManStartCommand::WaitForSocketCallback UPDATE_AD_GENERIC
07/07 15:02:20 (fd:3) (pid:2360) 
07/07 15:02:20 (fd:3) (pid:2360) Return from Timer handler 6 (update_collector)
07/07 15:02:20 (fd:3) (pid:2360) PRIV_CONDOR --> PRIV_CONDOR at ..\src\condor_daemon_core.V6\daemon_core.cpp:3548
07/07 15:02:20 (fd:3) (pid:2360) DaemonCore Timeout() Complete, returning 59 
07/07 15:02:20 (fd:3) (pid:2360) selector 0012FA70 resetting
07/07 15:02:20 (fd:3) (pid:2360) selector 0012FA70 adding fd 1772 ()
07/07 15:02:20 (fd:3) (pid:2360) selector 0012FA70 adding fd 1768 ()
07/07 15:02:20 (fd:3) (pid:2360) selector 0012FA70 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) selector 0012FA70 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) selector 0012FA70 adding fd 1788 ()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) selector 0012FA08 resetting
07/07 15:02:20 (fd:3) (pid:2360) selector 0012FA08 resetting
07/07 15:02:20 (fd:3) (pid:2360) selector 0012FA08 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) selector 0012FA08 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) CONNECT src=<172.17.7.223:4419> fd=1744 dst=<172.17.7.223:9618>
07/07 15:02:20 (fd:3) (pid:2360) Calling Handler <SecManStartCommand::WaitForSocketCallback UPDATE_AD_GENERIC> for Socket <<172.17.7.223:9618>>
07/07 15:02:20 (fd:3) (pid:2360) Calling Handler <SecManStartCommand::WaitForSocketCallback UPDATE_AD_GENERIC> (2)
07/07 15:02:20 (fd:3) (pid:2360) Cancel_Socket: cancelled socket 2 <<172.17.7.223:9618>> 00C1ABE8
07/07 15:02:20 (fd:3) (pid:2360) 
07/07 15:02:20 (fd:3) (pid:2360) DaemonCore--> Sockets Registered
07/07 15:02:20 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:20 (fd:3) (pid:2360) DaemonCore--> 0: 1772 <NULL> DC Command Handler
07/07 15:02:20 (fd:3) (pid:2360) DaemonCore--> 1: 1768 <NULL> DC Command Handler
07/07 15:02:20 (fd:3) (pid:2360) 
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: resuming command 60010 UPDATE_AD_GENERIC to <172.17.7.223:9618> from TCP port 4419 (non-blocking).
07/07 15:02:20 (fd:3) (pid:2360) Config 'SEC_DEFAULT_AUTHENTICATION': using prefix 'credd.' ==> 'REQUIRED'
07/07 15:02:20 (fd:3) (pid:2360) Config 'SEC_DEFAULT_ENCRYPTION': using prefix 'credd.' ==> 'REQUIRED'
07/07 15:02:20 (fd:3) (pid:2360) Config 'SEC_DEFAULT_INTEGRITY': using prefix 'credd.' ==> 'REQUIRED'
07/07 15:02:20 (fd:3) (pid:2360) Config 'SEC_DEFAULT_NEGOTIATION': using prefix 'credd.' ==> 'REQUIRED'
07/07 15:02:20 (fd:3) (pid:2360) Config 'SEC_DEFAULT_AUTHENTICATION_METHODS': using prefix 'credd.' ==> 'NTSSPI'
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: no cached key for {<172.17.7.223:9618>,<60010>}.
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: Security Policy:
MyType = ""
TargetType = ""
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
OutgoingNegotiation = "REQUIRED"
Authentication = "REQUIRED"
Encryption = "REQUIRED"
Integrity = "REQUIRED"
Enact = "NO"
Subsystem = "CREDD"
ParentUniqueID = "electrabel:2064:1278514935"
ServerPid = 2360
SessionDuration = "86400"
NewSession = "YES"
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: negotiating security for command 60010.
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: sending DC_AUTHENTICATE command
07/07 15:02:20 (fd:3) (pid:2360) SECMAN: sending following classad:
MyType = ""
TargetType = ""
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
OutgoingNegotiation = "REQUIRED"
Authentication = "REQUIRED"
Encryption = "REQUIRED"
Integrity = "REQUIRED"
Enact = "NO"
Subsystem = "CREDD"
ParentUniqueID = "electrabel:2064:1278514935"
ServerPid = 2360
SessionDuration = "86400"
NewSession = "YES"
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
ServerCommandSock = "<172.17.7.223:9620>"
Command = 60010
AuthCommand = 58
07/07 15:02:20 (fd:3) (pid:2360) selector 0012F6C0 resetting
07/07 15:02:20 (fd:3) (pid:2360) condor_write(fd=1744 <172.17.7.223:9618>,,size=500,timeout=20,flags=0)
07/07 15:02:20 (fd:3) (pid:2360) selector 0012F6C0 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) selector 0012F6C0 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) selector 0012F6C0 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) selector 0012F6C0 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [send] in condor_rw.cpp:357 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [send] in condor_rw.cpp:357 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [send] in condor_rw.cpp:359 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [send] in condor_rw.cpp:359 924()
07/07 15:02:20 (fd:3) (pid:2360) selector 0012F884 resetting
07/07 15:02:20 (fd:3) (pid:2360) selector 0012F884 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:20 (fd:3) (pid:2360) SEC_TCP_SESSION_DEADLINE is undefined, using default value of 120
07/07 15:02:20 (fd:3) (pid:2360) 
07/07 15:02:20 (fd:3) (pid:2360) DaemonCore--> Sockets Registered
07/07 15:02:20 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:20 (fd:3) (pid:2360) DaemonCore--> 0: 1772 <NULL> DC Command Handler
07/07 15:02:20 (fd:3) (pid:2360) DaemonCore--> 1: 1768 <NULL> DC Command Handler
07/07 15:02:20 (fd:3) (pid:2360) DaemonCore--> 2: 1744 <172.17.7.223:9618> SecManStartCommand::WaitForSocketCallback UPDATE_AD_GENERIC
07/07 15:02:20 (fd:3) (pid:2360) 
07/07 15:02:20 (fd:3) (pid:2360) Return from Handler <SecManStartCommand::WaitForSocketCallback UPDATE_AD_GENERIC>
07/07 15:02:20 (fd:3) (pid:2360) PRIV_CONDOR --> PRIV_CONDOR at ..\src\condor_daemon_core.V6\daemon_core.cpp:3548
07/07 15:02:20 (fd:3) (pid:2360) In DaemonCore Timeout()
07/07 15:02:20 (fd:3) (pid:2360) 
07/07 15:02:20 (fd:3) (pid:2360) DaemonCore--> Timers
07/07 15:02:20 (fd:3) (pid:2360) DaemonCore--> ~~~~~~
07/07 15:02:20 (fd:3) (pid:2360) DaemonCore--> id = 7, when = 1278514999, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:20 (fd:3) (pid:2360) DaemonCore--> id = 2, when = 1278515239, period = 300, handler_descrip=<check_session_cache>
07/07 15:02:20 (fd:3) (pid:2360) DaemonCore--> id = 6, when = 1278515240, period = 300, handler_descrip=<update_collector>
07/07 15:02:20 (fd:3) (pid:2360) DaemonCore--> id = 5, when = 1278516110, period = 1170, handler_descrip=<DaemonCore::SendAliveToParent>
07/07 15:02:20 (fd:3) (pid:2360) DaemonCore--> id = 3, when = 1278516740, period = 1801, handler_descrip=<handle_cookie_refresh>
07/07 15:02:20 (fd:3) (pid:2360) DaemonCore--> id = 8, when = 1278543739, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:20 (fd:3) (pid:2360) DaemonCore--> id = 4, when = 1278543865, period = 28926, handler_descrip=<DaemonCore::refreshDNS()>
07/07 15:02:20 (fd:3) (pid:2360) 
07/07 15:02:20 (fd:3) (pid:2360) DaemonCore Timeout() Complete, returning 59 
07/07 15:02:20 (fd:3) (pid:2360) selector 0012FA70 resetting
07/07 15:02:20 (fd:3) (pid:2360) selector 0012FA70 adding fd 1772 ()
07/07 15:02:20 (fd:3) (pid:2360) selector 0012FA70 adding fd 1768 ()
07/07 15:02:20 (fd:3) (pid:2360) selector 0012FA70 adding fd 1744 ()
07/07 15:02:20 (fd:3) (pid:2360) selector 0012FA70 adding fd 1788 ()
07/07 15:02:20 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:20 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) Calling Handler <SecManStartCommand::WaitForSocketCallback UPDATE_AD_GENERIC> for Socket <<172.17.7.223:9618>>
07/07 15:02:21 (fd:3) (pid:2360) Calling Handler <SecManStartCommand::WaitForSocketCallback UPDATE_AD_GENERIC> (2)
07/07 15:02:21 (fd:3) (pid:2360) Cancel_Socket: cancelled socket 2 <<172.17.7.223:9618>> 00C1ABE8
07/07 15:02:21 (fd:3) (pid:2360) 
07/07 15:02:21 (fd:3) (pid:2360) DaemonCore--> Sockets Registered
07/07 15:02:21 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:21 (fd:3) (pid:2360) DaemonCore--> 0: 1772 <NULL> DC Command Handler
07/07 15:02:21 (fd:3) (pid:2360) DaemonCore--> 1: 1768 <NULL> DC Command Handler
07/07 15:02:21 (fd:3) (pid:2360) 
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: resuming command 60010 UPDATE_AD_GENERIC to <172.17.7.223:9618> from TCP port 4419 (non-blocking).
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F884 resetting
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F884 adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F744 resetting
07/07 15:02:21 (fd:3) (pid:2360) condor_read(fd=1744 <172.17.7.223:9618>,,size=5,timeout=20,flags=0)
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F744 adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) condor_read(): fd=1744
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F728 resetting
07/07 15:02:21 (fd:3) (pid:2360) condor_read(fd=1744 <172.17.7.223:9618>,,size=290,timeout=20,flags=0)
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F728 adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) condor_read(): fd=1744
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: server responded with:
MyType = "(unknown type)"
TargetType = "(unknown type)"
Authentication = "YES"
Encryption = "YES"
Integrity = "YES"
AuthMethodsList = "NTSSPI"
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:3) (pid:2360) SECMAN: new session, doing initial authentication.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: authenticating RIGHT NOW.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: AuthMethodsList: NTSSPI
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: Auth methods: NTSSPI
07/07 15:02:21 (fd:3) (pid:2360) AUTHENTICATE: in authenticate( addr == '<172.17.7.223:9618>', methods == 'NTSSPI')
07/07 15:02:21 (fd:3) (pid:2360) AUTHENTICATE: can still try these methods: NTSSPI
07/07 15:02:21 (fd:3) (pid:2360) HANDSHAKE: in handshake(my_methods = 'NTSSPI')
07/07 15:02:21 (fd:3) (pid:2360) HANDSHAKE: handshake() - i am the client
07/07 15:02:21 (fd:3) (pid:2360) HANDSHAKE: sending (methods == 16) to server
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F68C resetting
07/07 15:02:21 (fd:3) (pid:2360) condor_write(fd=1744 <172.17.7.223:9618>,,size=13,timeout=20,flags=0)
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F68C adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F68C adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F68C adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F68C adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [send] in condor_rw.cpp:357 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [send] in condor_rw.cpp:357 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [send] in condor_rw.cpp:359 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [send] in condor_rw.cpp:359 924()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F688 resetting
07/07 15:02:21 (fd:3) (pid:2360) condor_read(fd=1744 <172.17.7.223:9618>,,size=5,timeout=20,flags=0)
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F688 adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) condor_read(): fd=1744
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F66C resetting
07/07 15:02:21 (fd:3) (pid:2360) condor_read(fd=1744 <172.17.7.223:9618>,,size=8,timeout=20,flags=0)
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F66C adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) condor_read(): fd=1744
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:21 (fd:3) (pid:2360) HANDSHAKE: server replied (method = 16)
07/07 15:02:21 (fd:3) (pid:2360) Config 'UID_DOMAIN': no prefix ==> 'pylos.logilab.fr'
07/07 15:02:21 (fd:3) (pid:2360) AUTHENTICATE: will try to use 16 (NTSSPI)
07/07 15:02:21 (fd:3) (pid:2360) sspi_client_auth() entered
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F648 resetting
07/07 15:02:21 (fd:3) (pid:2360) condor_write(fd=1744 <172.17.7.223:9618>,,size=13,timeout=20,flags=0)
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F648 adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F648 adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F648 adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F648 adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [send] in condor_rw.cpp:357 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [send] in condor_rw.cpp:357 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [send] in condor_rw.cpp:359 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [send] in condor_rw.cpp:359 924()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F648 resetting
07/07 15:02:21 (fd:3) (pid:2360) condor_write(fd=1744 <172.17.7.223:9618>,,size=60,timeout=20,flags=0)
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F648 adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F648 adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F648 adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F648 adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [send] in condor_rw.cpp:357 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [send] in condor_rw.cpp:357 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [send] in condor_rw.cpp:359 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [send] in condor_rw.cpp:359 924()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F634 resetting
07/07 15:02:21 (fd:3) (pid:2360) condor_read(fd=1744 <172.17.7.223:9618>,,size=5,timeout=20,flags=0)
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F634 adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) condor_read(): fd=1744
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F618 resetting
07/07 15:02:21 (fd:3) (pid:2360) condor_read(fd=1744 <172.17.7.223:9618>,,size=8,timeout=20,flags=0)
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F618 adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) condor_read(): fd=1744
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F660 resetting
07/07 15:02:21 (fd:3) (pid:2360) condor_read(fd=1744 <172.17.7.223:9618>,,size=5,timeout=20,flags=0)
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F660 adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) condor_read(): fd=1744
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F644 resetting
07/07 15:02:21 (fd:3) (pid:2360) condor_read(fd=1744 <172.17.7.223:9618>,,size=238,timeout=20,flags=0)
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F644 adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) condor_read(): fd=1744
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:21 (fd:3) (pid:2360) sspi_client_auth() looping
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F648 resetting
07/07 15:02:21 (fd:3) (pid:2360) condor_write(fd=1744 <172.17.7.223:9618>,,size=13,timeout=20,flags=0)
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F648 adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F648 adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F648 adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F648 adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [send] in condor_rw.cpp:357 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [send] in condor_rw.cpp:357 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [send] in condor_rw.cpp:359 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [send] in condor_rw.cpp:359 924()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F648 resetting
07/07 15:02:21 (fd:3) (pid:2360) condor_write(fd=1744 <172.17.7.223:9618>,,size=77,timeout=20,flags=0)
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F648 adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F648 adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F648 adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F648 adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [send] in condor_rw.cpp:357 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [send] in condor_rw.cpp:357 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [send] in condor_rw.cpp:359 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [send] in condor_rw.cpp:359 924()
07/07 15:02:21 (fd:3) (pid:2360) sspi_client_auth() exiting
07/07 15:02:21 (fd:3) (pid:2360) AUTHENTICATE: auth_status == 16 (NTSSPI)
07/07 15:02:21 (fd:3) (pid:2360) Authentication was a Success.
07/07 15:02:21 (fd:3) (pid:2360) ZKM: setting default map to (null)
07/07 15:02:21 (fd:3) (pid:2360) ZKM: post-map: current user is '(null)'
07/07 15:02:21 (fd:3) (pid:2360) ZKM: post-map: current domain is '(null)'
07/07 15:02:21 (fd:3) (pid:2360) ZKM: post-map: current FQU is '(null)'
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F72C resetting
07/07 15:02:21 (fd:3) (pid:2360) condor_read(fd=1744 <172.17.7.223:9618>,,size=5,timeout=20,flags=0)
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F72C adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) condor_read(): fd=1744
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F710 resetting
07/07 15:02:21 (fd:3) (pid:2360) condor_read(fd=1744 <172.17.7.223:9618>,,size=8,timeout=20,flags=0)
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F710 adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) condor_read(): fd=1744
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F72C resetting
07/07 15:02:21 (fd:3) (pid:2360) condor_read(fd=1744 <172.17.7.223:9618>,,size=5,timeout=20,flags=0)
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F72C adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) condor_read(): fd=1744
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F710 resetting
07/07 15:02:21 (fd:3) (pid:2360) condor_read(fd=1744 <172.17.7.223:9618>,,size=72,timeout=20,flags=0)
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F710 adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) condor_read(): fd=1744
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: about to enable message authenticator.
07/07 15:02:21 (fd:3) (pid:2360) SEC_DEBUG_PRINT_KEYS is undefined, using default value of False
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: successfully enabled message authenticator!
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: about to enable encryption.
07/07 15:02:21 (fd:3) (pid:2360) SEC_DEBUG_PRINT_KEYS is undefined, using default value of False
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: successfully enabled encryption!
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F850 resetting
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F850 adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F710 resetting
07/07 15:02:21 (fd:3) (pid:2360) condor_read(fd=1744 <172.17.7.223:9618>,,size=21,timeout=20,flags=0)
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F710 adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) condor_read(): fd=1744
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F6F4 resetting
07/07 15:02:21 (fd:3) (pid:2360) condor_read(fd=1744 <172.17.7.223:9618>,,size=334,timeout=20,flags=0)
07/07 15:02:21 (fd:3) (pid:2360) selector 0012F6F4 adding fd 1744 ()
07/07 15:02:21 (fd:3) (pid:2360) condor_read(): fd=1744
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:21 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: received post-auth classad:
MyType = "(unknown type)"
TargetType = "(unknown type)"
User = "SYSTEM@nt authority"
TriedAuthentication = TRUE
Sid = "electrabel:1520:1278514940:0"
ValidCommands = "4,17,21,42,44,45,47,52,54,55,57,58,59,61,63,64,66,67,70,72,60000,60008,60017,60004,5,6,7,9,12,43,46,48,50,53,56,62,65,68,71,74,60007,457"
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: policy to be cached:
MyType = ""
TargetType = ""
OutgoingNegotiation = "REQUIRED"
Subsystem = "CREDD"
Command = 60010
AuthCommand = 58
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
Enact = "YES"
AuthMethodsList = "NTSSPI"
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
Authentication = "YES"
Encryption = "YES"
Integrity = "YES"
SessionDuration = "86400"
UseSession = "YES"
Sid = "electrabel:1520:1278514940:0"
MyRemoteUserName = "SYSTEM@nt authority"
ValidCommands = "4,17,21,42,44,45,47,52,54,55,57,58,59,61,63,64,66,67,70,72,60000,60008,60017,60004,5,6,7,9,12,43,46,48,50,53,56,62,65,68,71,74,60007,457"
TriedAuthentication = TRUE
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: added session electrabel:1520:1278514940:0 to cache for 86400 seconds.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<4>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<17>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<21>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<42>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<44>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<45>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<47>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<52>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<54>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<55>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<57>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<58>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<59>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<61>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<63>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<64>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<66>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<67>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<70>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<72>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<60000>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<60008>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<60017>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<60004>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<5>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<6>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<7>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<9>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<12>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<43>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<46>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<48>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<50>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<53>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<56>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<62>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<65>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<68>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<71>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<74>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<60007>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: command {<172.17.7.223:9618>,<457>} mapped to session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: startCommand succeeded.
07/07 15:02:21 (fd:3) (pid:2360) Authorizing server '*/172.17.7.223'.
07/07 15:02:21 (fd:3) (pid:2360) CLOSE <172.17.7.223:4419> fd=1744
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: succesfully created security session to <172.17.7.223:9618> via TCP!
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: resuming command 58 UPDATE_AD_GENERIC to collector electrabel.pylos.logilab.fr from UDP port 4418 (non-blocking).
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: using session electrabel:1520:1278514940:0 for {<172.17.7.223:9618>,<58>}.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: found cached session id electrabel:1520:1278514940:0 for {<172.17.7.223:9618>,<58>}.
07/07 15:02:21 (fd:3) (pid:2360) SEC_DEBUG_PRINT_KEYS is undefined, using default value of False
MyType = ""
TargetType = ""
ParentUniqueID = "electrabel:2064:1278514935"
ServerPid = 2360
OutgoingNegotiation = "REQUIRED"
Subsystem = "CREDD"
Command = 60010
AuthCommand = 58
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
Enact = "YES"
AuthMethodsList = "NTSSPI"
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
Authentication = "YES"
Encryption = "YES"
Integrity = "YES"
SessionDuration = "86400"
UseSession = "YES"
Sid = "electrabel:1520:1278514940:0"
MyRemoteUserName = "SYSTEM@nt authority"
ValidCommands = "4,17,21,42,44,45,47,52,54,55,57,58,59,61,63,64,66,67,70,72,60000,60008,60017,60004,5,6,7,9,12,43,46,48,50,53,56,62,65,68,71,74,60007,457"
TriedAuthentication = TRUE
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: Security Policy:
MyType = ""
TargetType = ""
ParentUniqueID = "electrabel:2064:1278514935"
ServerPid = 2360
OutgoingNegotiation = "REQUIRED"
Subsystem = "CREDD"
Command = 60010
AuthCommand = 58
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
Enact = "YES"
AuthMethodsList = "NTSSPI"
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
Authentication = "YES"
Encryption = "YES"
Integrity = "YES"
SessionDuration = "86400"
UseSession = "YES"
Sid = "electrabel:1520:1278514940:0"
MyRemoteUserName = "SYSTEM@nt authority"
ValidCommands = "4,17,21,42,44,45,47,52,54,55,57,58,59,61,63,64,66,67,70,72,60000,60008,60017,60004,5,6,7,9,12,43,46,48,50,53,56,62,65,68,71,74,60007,457"
TriedAuthentication = TRUE
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: negotiating security for command 58.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: UDP, m_have_session == 1, peer_can_negotiate == 1
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: UDP has session electrabel:1520:1278514940:0.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: about to enable message authenticator.
07/07 15:02:21 (fd:3) (pid:2360) SEC_DEBUG_PRINT_KEYS is undefined, using default value of False
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: successfully enabled message authenticator!
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: about to enable encryption.
07/07 15:02:21 (fd:3) (pid:2360) SEC_DEBUG_PRINT_KEYS is undefined, using default value of False
07/07 15:02:21 (fd:3) (pid:2360) set_encryption_id: setting key length 48
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: successfully enabled encryption.
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: sending DC_AUTHENTICATE command
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: sending following classad:
MyType = ""
TargetType = ""
ParentUniqueID = "electrabel:2064:1278514935"
ServerPid = 2360
OutgoingNegotiation = "REQUIRED"
Subsystem = "CREDD"
AuthCommand = 58
Enact = "YES"
AuthMethodsList = "NTSSPI"
AuthMethods = "NTSSPI"
CryptoMethods = "3DES,BLOWFISH"
Authentication = "YES"
Encryption = "YES"
Integrity = "YES"
SessionDuration = "86400"
UseSession = "YES"
Sid = "electrabel:1520:1278514940:0"
MyRemoteUserName = "SYSTEM@nt authority"
ValidCommands = "4,17,21,42,44,45,47,52,54,55,57,58,59,61,63,64,66,67,70,72,60000,60008,60017,60004,5,6,7,9,12,43,46,48,50,53,56,62,65,68,71,74,60007,457"
TriedAuthentication = TRUE
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
ServerCommandSock = "<172.17.7.223:9620>"
Command = 58
07/07 15:02:21 (fd:3) (pid:2360) SECMAN: startCommand succeeded.
07/07 15:02:21 (fd:3) (pid:2360) Authorizing server '*/172.17.7.223'.
07/07 15:02:21 (fd:3) (pid:2360) SEND [1000] <172.17.7.223:4418> <172.17.7.223:9618>
07/07 15:02:21 (fd:3) (pid:2360) SEND [626] <172.17.7.223:4418> <172.17.7.223:9618>
07/07 15:02:21 (fd:3) (pid:2360) Return from Handler <SecManStartCommand::WaitForSocketCallback UPDATE_AD_GENERIC>
07/07 15:02:21 (fd:3) (pid:2360) PRIV_CONDOR --> PRIV_CONDOR at ..\src\condor_daemon_core.V6\daemon_core.cpp:3548
07/07 15:02:21 (fd:3) (pid:2360) In DaemonCore Timeout()
07/07 15:02:21 (fd:3) (pid:2360) 
07/07 15:02:21 (fd:3) (pid:2360) DaemonCore--> Timers
07/07 15:02:21 (fd:3) (pid:2360) DaemonCore--> ~~~~~~
07/07 15:02:21 (fd:3) (pid:2360) DaemonCore--> id = 7, when = 1278514999, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:21 (fd:3) (pid:2360) DaemonCore--> id = 2, when = 1278515239, period = 300, handler_descrip=<check_session_cache>
07/07 15:02:21 (fd:3) (pid:2360) DaemonCore--> id = 6, when = 1278515240, period = 300, handler_descrip=<update_collector>
07/07 15:02:21 (fd:3) (pid:2360) DaemonCore--> id = 5, when = 1278516110, period = 1170, handler_descrip=<DaemonCore::SendAliveToParent>
07/07 15:02:21 (fd:3) (pid:2360) DaemonCore--> id = 3, when = 1278516740, period = 1801, handler_descrip=<handle_cookie_refresh>
07/07 15:02:21 (fd:3) (pid:2360) DaemonCore--> id = 8, when = 1278543739, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:21 (fd:3) (pid:2360) DaemonCore--> id = 4, when = 1278543865, period = 28926, handler_descrip=<DaemonCore::refreshDNS()>
07/07 15:02:21 (fd:3) (pid:2360) 
07/07 15:02:21 (fd:3) (pid:2360) DaemonCore Timeout() Complete, returning 58 
07/07 15:02:21 (fd:3) (pid:2360) selector 0012FA70 resetting
07/07 15:02:21 (fd:3) (pid:2360) selector 0012FA70 adding fd 1772 ()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012FA70 adding fd 1768 ()
07/07 15:02:21 (fd:3) (pid:2360) selector 0012FA70 adding fd 1788 ()
07/07 15:02:21 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:21 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:32 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:32 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:32 (fd:3) (pid:2360) ACCEPT from=<172.17.7.223:4442> newfd=1752 to=<172.17.7.223:9620>
07/07 15:02:32 (fd:3) (pid:2360) in DaemonCore NewTimer()
07/07 15:02:32 (fd:3) (pid:2360) 
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore--> Timers
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore--> ~~~~~~
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore--> id = 7, when = 1278514999, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore--> id = 9, when = 1278515152, period = 0, handler_descrip=<DaemonCore::HandleReqSocketTimerHandler>
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore--> id = 2, when = 1278515239, period = 300, handler_descrip=<check_session_cache>
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore--> id = 6, when = 1278515240, period = 300, handler_descrip=<update_collector>
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore--> id = 5, when = 1278516110, period = 1170, handler_descrip=<DaemonCore::SendAliveToParent>
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore--> id = 3, when = 1278516740, period = 1801, handler_descrip=<handle_cookie_refresh>
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore--> id = 8, when = 1278543739, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore--> id = 4, when = 1278543865, period = 28926, handler_descrip=<DaemonCore::refreshDNS()>
07/07 15:02:32 (fd:3) (pid:2360) 
07/07 15:02:32 (fd:3) (pid:2360) leaving DaemonCore NewTimer, id=9
07/07 15:02:32 (fd:3) (pid:2360) 
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore--> Sockets Registered
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore--> 0: 1772 <NULL> DC Command Handler
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore--> 1: 1768 <NULL> DC Command Handler
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore--> 2: 1752 Incoming command DaemonCore::HandleReqSocketHandler
07/07 15:02:32 (fd:3) (pid:2360) 
07/07 15:02:32 (fd:3) (pid:2360) PRIV_CONDOR --> PRIV_CONDOR at ..\src\condor_daemon_core.V6\daemon_core.cpp:3548
07/07 15:02:32 (fd:3) (pid:2360) In DaemonCore Timeout()
07/07 15:02:32 (fd:3) (pid:2360) 
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore--> Timers
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore--> ~~~~~~
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore--> id = 7, when = 1278514999, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore--> id = 9, when = 1278515152, period = 0, handler_descrip=<DaemonCore::HandleReqSocketTimerHandler>
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore--> id = 2, when = 1278515239, period = 300, handler_descrip=<check_session_cache>
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore--> id = 6, when = 1278515240, period = 300, handler_descrip=<update_collector>
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore--> id = 5, when = 1278516110, period = 1170, handler_descrip=<DaemonCore::SendAliveToParent>
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore--> id = 3, when = 1278516740, period = 1801, handler_descrip=<handle_cookie_refresh>
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore--> id = 8, when = 1278543739, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore--> id = 4, when = 1278543865, period = 28926, handler_descrip=<DaemonCore::refreshDNS()>
07/07 15:02:32 (fd:3) (pid:2360) 
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore Timeout() Complete, returning 47 
07/07 15:02:32 (fd:3) (pid:2360) selector 0012FA70 resetting
07/07 15:02:32 (fd:3) (pid:2360) selector 0012FA70 adding fd 1772 ()
07/07 15:02:32 (fd:3) (pid:2360) selector 0012FA70 adding fd 1768 ()
07/07 15:02:32 (fd:3) (pid:2360) selector 0012FA70 adding fd 1752 ()
07/07 15:02:32 (fd:3) (pid:2360) selector 0012FA70 adding fd 1788 ()
07/07 15:02:32 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:32 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:32 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:32 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:32 (fd:3) (pid:2360) Calling Handler <DaemonCore::HandleReqSocketHandler> for Socket <Incoming command>
07/07 15:02:32 (fd:3) (pid:2360) Calling Handler <DaemonCore::HandleReqSocketHandler> (2)
07/07 15:02:32 (fd:3) (pid:2360) In cancel_timer(), id=9
07/07 15:02:32 (fd:3) (pid:2360) Cancel_Socket: cancelled socket 2 <Incoming command> 00C1ABE8
07/07 15:02:32 (fd:3) (pid:2360) 
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore--> Sockets Registered
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore--> ~~~~~~~~~~~~~~~~~~~
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore--> 0: 1772 <NULL> DC Command Handler
07/07 15:02:32 (fd:3) (pid:2360) DaemonCore--> 1: 1768 <NULL> DC Command Handler
07/07 15:02:32 (fd:3) (pid:2360) 
07/07 15:02:32 (fd:3) (pid:2360) selector 0012F744 resetting
07/07 15:02:32 (fd:3) (pid:2360) condor_read(fd=1752 <172.17.7.223:4442>,,size=4,timeout=1,flags=2)
07/07 15:02:32 (fd:3) (pid:2360) selector 0012F744 adding fd 1752 ()
07/07 15:02:32 (fd:3) (pid:2360) condor_read(): fd=1752
07/07 15:02:32 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:32 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:32 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:32 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:32 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:32 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:32 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:32 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:32 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:32 (fd:3) (pid:2360) selector 0012F6A4 resetting
07/07 15:02:32 (fd:3) (pid:2360) condor_read(fd=1752 <172.17.7.223:4442>,,size=5,timeout=1,flags=0)
07/07 15:02:32 (fd:3) (pid:2360) selector 0012F6A4 adding fd 1752 ()
07/07 15:02:32 (fd:3) (pid:2360) condor_read(): fd=1752
07/07 15:02:32 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:32 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:32 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:32 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:32 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:32 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:32 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:32 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:32 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:32 (fd:3) (pid:2360) selector 0012F688 resetting
07/07 15:02:32 (fd:3) (pid:2360) condor_read(fd=1752 <172.17.7.223:4442>,,size=463,timeout=1,flags=0)
07/07 15:02:32 (fd:3) (pid:2360) selector 0012F688 adding fd 1752 ()
07/07 15:02:32 (fd:3) (pid:2360) condor_read(): fd=1752
07/07 15:02:32 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:32 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:32 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:32 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:32 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:32 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:32 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:32 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:32 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:32 (fd:3) (pid:2360) DC_AUTHENTICATE: received DC_AUTHENTICATE from <172.17.7.223:4442>
07/07 15:02:32 (fd:3) (pid:2360) 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:3) (pid:2360) Config 'SEC_DEFAULT_AUTHENTICATION': using prefix 'credd.' ==> 'REQUIRED'
07/07 15:02:32 (fd:3) (pid:2360) Config 'SEC_DEFAULT_ENCRYPTION': using prefix 'credd.' ==> 'REQUIRED'
07/07 15:02:32 (fd:3) (pid:2360) Config 'SEC_DEFAULT_INTEGRITY': using prefix 'credd.' ==> 'REQUIRED'
07/07 15:02:32 (fd:3) (pid:2360) Config 'SEC_DEFAULT_NEGOTIATION': using prefix 'credd.' ==> 'REQUIRED'
07/07 15:02:32 (fd:3) (pid:2360) Config 'SEC_DAEMON_AUTHENTICATION_METHODS': using prefix 'credd.' ==> 'PASSWORD'
07/07 15:02:32 (fd:3) (pid:2360) DC_AUTHENTICATE: our_policy:
MyType = ""
TargetType = ""
AuthMethods = "PASSWORD"
CryptoMethods = "3DES,BLOWFISH"
OutgoingNegotiation = "REQUIRED"
Authentication = "REQUIRED"
Encryption = "REQUIRED"
Integrity = "REQUIRED"
Enact = "NO"
Subsystem = "CREDD"
ParentUniqueID = "electrabel:2064:1278514935"
ServerPid = 2360
SessionDuration = "86400"
07/07 15:02:32 (fd:3) (pid:2360) DC_AUTHENTICATE: the_policy:
MyType = ""
TargetType = ""
Authentication = "YES"
Encryption = "YES"
Integrity = "YES"
AuthMethodsList = ""
CryptoMethods = "3DES,BLOWFISH"
SessionDuration = "86400"
Enact = "YES"
07/07 15:02:33 (fd:3) (pid:2360) DC_AUTHENTICATE: generating 3DES key for session electrabel:2360:1278514952:0...
07/07 15:02:33 (fd:3) (pid:2360) SEC_DEBUG_PRINT_KEYS is undefined, using default value of False
07/07 15:02:33 (fd:3) (pid:2360) SECMAN: Sending following response ClassAd:
MyType = ""
TargetType = ""
Authentication = "YES"
Encryption = "YES"
Integrity = "YES"
AuthMethodsList = ""
CryptoMethods = "3DES,BLOWFISH"
SessionDuration = "86400"
Enact = "YES"
RemoteVersion = "$CondorVersion: 7.4.1 Dec 17 2009 BuildID: 204351 $"
07/07 15:02:33 (fd:3) (pid:2360) selector 0012F6A8 resetting
07/07 15:02:33 (fd:3) (pid:2360) condor_write(fd=1752 <172.17.7.223:4442>,,size=266,timeout=20,flags=0)
07/07 15:02:33 (fd:3) (pid:2360) selector 0012F6A8 adding fd 1752 ()
07/07 15:02:33 (fd:3) (pid:2360) selector 0012F6A8 adding fd 1752 ()
07/07 15:02:33 (fd:3) (pid:2360) selector 0012F6A8 adding fd 1752 ()
07/07 15:02:33 (fd:3) (pid:2360) selector 0012F6A8 adding fd 1752 ()
07/07 15:02:33 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:33 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:33 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:33 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:33 (fd:3) (pid:2360) Entering thread safe start [send] in condor_rw.cpp:357 924()
07/07 15:02:33 (fd:3) (pid:2360) Leaving thread safe start [send] in condor_rw.cpp:357 924()
07/07 15:02:33 (fd:3) (pid:2360) Entering thread safe stop [send] in condor_rw.cpp:359 924()
07/07 15:02:33 (fd:3) (pid:2360) Leaving thread safe stop [send] in condor_rw.cpp:359 924()
07/07 15:02:33 (fd:3) (pid:2360) SECMAN: new session, doing initial authentication.
07/07 15:02:33 (fd:3) (pid:2360) DC_AUTHENTICATE: authenticating RIGHT NOW.
07/07 15:02:33 (fd:3) (pid:2360) AUTHENTICATE: in authenticate( addr == NULL, methods == '')
07/07 15:02:33 (fd:3) (pid:2360) AUTHENTICATE: can still try these methods: 
07/07 15:02:33 (fd:3) (pid:2360) HANDSHAKE: in handshake(my_methods = '')
07/07 15:02:33 (fd:3) (pid:2360) HANDSHAKE: handshake() - i am the server
07/07 15:02:33 (fd:3) (pid:2360) selector 0012F520 resetting
07/07 15:02:33 (fd:3) (pid:2360) condor_read(fd=1752 <172.17.7.223:4442>,,size=5,timeout=20,flags=0)
07/07 15:02:33 (fd:3) (pid:2360) selector 0012F520 adding fd 1752 ()
07/07 15:02:33 (fd:3) (pid:2360) condor_read(): fd=1752
07/07 15:02:33 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:33 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:33 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:33 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:33 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:33 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:33 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:33 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:33 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:33 (fd:3) (pid:2360) selector 0012F504 resetting
07/07 15:02:33 (fd:3) (pid:2360) condor_read(fd=1752 <172.17.7.223:4442>,,size=8,timeout=20,flags=0)
07/07 15:02:33 (fd:3) (pid:2360) selector 0012F504 adding fd 1752 ()
07/07 15:02:33 (fd:3) (pid:2360) condor_read(): fd=1752
07/07 15:02:33 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:33 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:33 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:33 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:33 (fd:3) (pid:2360) condor_read(): select returned 1
07/07 15:02:33 (fd:3) (pid:2360) Entering thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:33 (fd:3) (pid:2360) Leaving thread safe start [recv] in condor_rw.cpp:156 924()
07/07 15:02:33 (fd:3) (pid:2360) Entering thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:33 (fd:3) (pid:2360) Leaving thread safe stop [recv] in condor_rw.cpp:158 924()
07/07 15:02:33 (fd:3) (pid:2360) HANDSHAKE: client sent (methods == 0)
07/07 15:02:33 (fd:3) (pid:2360) HANDSHAKE: i picked (method == 0)
07/07 15:02:33 (fd:3) (pid:2360) selector 0012F524 resetting
07/07 15:02:33 (fd:3) (pid:2360) condor_write(fd=1752 <172.17.7.223:4442>,,size=13,timeout=20,flags=0)
07/07 15:02:33 (fd:3) (pid:2360) selector 0012F524 adding fd 1752 ()
07/07 15:02:33 (fd:3) (pid:2360) selector 0012F524 adding fd 1752 ()
07/07 15:02:33 (fd:3) (pid:2360) selector 0012F524 adding fd 1752 ()
07/07 15:02:33 (fd:3) (pid:2360) selector 0012F524 adding fd 1752 ()
07/07 15:02:33 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:33 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
07/07 15:02:33 (fd:3) (pid:2360) Entering thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:33 (fd:3) (pid:2360) Leaving thread safe stop [select] in selector.cpp:317 924()
07/07 15:02:33 (fd:3) (pid:2360) Entering thread safe start [send] in condor_rw.cpp:357 924()
07/07 15:02:33 (fd:3) (pid:2360) Leaving thread safe start [send] in condor_rw.cpp:357 924()
07/07 15:02:33 (fd:3) (pid:2360) Entering thread safe stop [send] in condor_rw.cpp:359 924()
07/07 15:02:33 (fd:3) (pid:2360) Leaving thread safe stop [send] in condor_rw.cpp:359 924()
07/07 15:02:33 (fd:3) (pid:2360) HANDSHAKE: client received (method == 0)
07/07 15:02:33 (fd:3) (pid:2360) AUTHENTICATE: no available authentication methods succeeded, failing!
07/07 15:02:33 (fd:3) (pid:2360) DC_AUTHENTICATE: authenticate failed: AUTHENTICATE:1003:Failed to authenticate with any method
07/07 15:02:33 (fd:3) (pid:2360) CLOSE <172.17.7.223:9620> fd=1752
07/07 15:02:33 (fd:3) (pid:2360) Return from Handler <DaemonCore::HandleReqSocketHandler>
07/07 15:02:33 (fd:3) (pid:2360) PRIV_CONDOR --> PRIV_CONDOR at ..\src\condor_daemon_core.V6\daemon_core.cpp:3548
07/07 15:02:33 (fd:3) (pid:2360) In DaemonCore Timeout()
07/07 15:02:33 (fd:3) (pid:2360) 
07/07 15:02:33 (fd:3) (pid:2360) DaemonCore--> Timers
07/07 15:02:33 (fd:3) (pid:2360) DaemonCore--> ~~~~~~
07/07 15:02:33 (fd:3) (pid:2360) DaemonCore--> id = 7, when = 1278514999, period = 0, handler_descrip=<dc_touch_log_file>
07/07 15:02:33 (fd:3) (pid:2360) DaemonCore--> id = 2, when = 1278515239, period = 300, handler_descrip=<check_session_cache>
07/07 15:02:33 (fd:3) (pid:2360) DaemonCore--> id = 6, when = 1278515240, period = 300, handler_descrip=<update_collector>
07/07 15:02:33 (fd:3) (pid:2360) DaemonCore--> id = 5, when = 1278516110, period = 1170, handler_descrip=<DaemonCore::SendAliveToParent>
07/07 15:02:33 (fd:3) (pid:2360) DaemonCore--> id = 3, when = 1278516740, period = 1801, handler_descrip=<handle_cookie_refresh>
07/07 15:02:33 (fd:3) (pid:2360) DaemonCore--> id = 8, when = 1278543739, period = 0, handler_descrip=<dc_touch_lock_files>
07/07 15:02:33 (fd:3) (pid:2360) DaemonCore--> id = 4, when = 1278543865, period = 28926, handler_descrip=<DaemonCore::refreshDNS()>
07/07 15:02:33 (fd:3) (pid:2360) 
07/07 15:02:33 (fd:3) (pid:2360) DaemonCore Timeout() Complete, returning 46 
07/07 15:02:33 (fd:3) (pid:2360) selector 0012FA70 resetting
07/07 15:02:33 (fd:3) (pid:2360) selector 0012FA70 adding fd 1772 ()
07/07 15:02:33 (fd:3) (pid:2360) selector 0012FA70 adding fd 1768 ()
07/07 15:02:33 (fd:3) (pid:2360) selector 0012FA70 adding fd 1788 ()
07/07 15:02:33 (fd:3) (pid:2360) Entering thread safe start [select] in selector.cpp:311 924()
07/07 15:02:33 (fd:3) (pid:2360) Leaving thread safe start [select] in selector.cpp:311 924()
