==>Running the container with these arguments: '-endorsed -Dalma.MicroArchive.DbDir=/alma/ACS-3.0/acsdata/config/MicroArchive alma.acs.container.AcsContainerRunner -containerName frodoContainer -OAport 3050 '
Starting Java/abeans application
Using endorsed jar files in: -Djava.endorsed.dirs=/alma/ACS-3.0/ACSSW/lib/endorsed:
exported ACS_ABEANS_CONFIG=/alma/ACS-3.0/acsdata/config/abeans/Config
ACS abeans configuration directory: /alma/ACS-3.0/acsdata/config/abeans/Config
exported CLASSPATH
exported MANAGER_REFERENCE=corbaloc::te49:3000/Manager
ACS Manager: corbaloc::te49:3000/Manager
exported MANAGER_COMPUTER_NAME=te49
exported ACS_NAME_SERVICE=corbaloc::te49:3001/NameService
ACS Name Service: corbaloc::te49:3001/NameService
exported ACS_INTERFACE_REPOSITORY=corbaloc::te49:3004/InterfaceRepository
ACS Interface Repository: corbaloc::te49:3004/InterfaceRepository
exported ACS_TMP=/alma/ACS-3.0/acsdata/tmp/ACS_INSTANCE.0
Running the following command:
java -classpath ../lib/schedulingTest.jar:/alma/ACS-3.0/ACSSW/lib/abeansACSPlug.jar:/alma/ACS-3.0/ACSSW/lib/abeansBaciModel.jar:/alma/ACS-3.0/ACSSW/lib/abeansgen.jar:/alma/ACS-3.0/ACSSW/lib/abeansNetBeansModule.jar:/alma/ACS-3.0/ACSSW/lib/abeansR2Components.jar:/alma/ACS-3.0/ACSSW/lib/ac.jar:/alma/ACS-3.0/ACSSW/lib/acsabeansExamples.jar:/alma/ACS-3.0/ACSSW/lib/AcsCommandCenterEntities.jar:/alma/ACS-3.0/ACSSW/lib/acscommandcenter.jar:/alma/ACS-3.0/ACSSW/lib/acscomponent.jar:/alma/ACS-3.0/ACSSW/lib/acserr.jar:/alma/ACS-3.0/ACSSW/lib/acserrj.jar:/alma/ACS-3.0/ACSSW/lib/ACSErrTICS.jar:/alma/ACS-3.0/ACSSW/lib/ACSErrTicsTCorr.jar:/alma/ACS-3.0/ACSSW/lib/ACSErrTypeAlarm.jar:/alma/ACS-3.0/ACSSW/lib/ACSErrTypeCommon.jar:/alma/ACS-3.0/ACSSW/lib/ACSErrTypeCORBA.jar:/alma/ACS-3.0/ACSSW/lib/ACSErrTypeCppNative.jar:/alma/ACS-3.0/ACSSW/lib/ACSErrTypeDevIO.jar:/alma/ACS-3.0/ACSSW/lib/ACSErrTypeJavaNative.jar:/alma/ACS-3.0/ACSSW/lib/ACSErrTypeMonitor.jar:/alma/ACS-3.0/ACSSW/lib/ACSErrTypeOK.jar:/alma/ACS-3.0/ACSSW/lib/ACSErrTypePythonNative.jar:/alma/ACS-3.0/ACSSW/lib/ACSErrTypeTest.jar:/alma/ACS-3.0/ACSSW/lib/acsexmplAmsSeqAbeans.jar:/alma/ACS-3.0/ACSSW/lib/acsexmplAmsSeq.jar:/alma/ACS-3.0/ACSSW/lib/acsexmplBuildingAbeans.jar:/alma/ACS-3.0/ACSSW/lib/acsexmplBuilding.jar:/alma/ACS-3.0/ACSSW/lib/acsexmplCalendarAbeans.jar:/alma/ACS-3.0/ACSSW/lib/acsexmplCalendar.jar:/alma/ACS-3.0/ACSSW/lib/acsexmplFridgeAbeans.jar:/alma/ACS-3.0/ACSSW/lib/acsexmplFridge.jar:/alma/ACS-3.0/ACSSW/lib/acsexmplHelloWorldAbeans.jar:/alma/ACS-3.0/ACSSW/lib/acsexmplHelloWorld.jar:/alma/ACS-3.0/ACSSW/lib/acsexmplLampAbeans.jar:/alma/ACS-3.0/ACSSW/lib/acsexmplLamp.jar:/alma/ACS-3.0/ACSSW/lib/acsexmplMountAbeans.jar:/alma/ACS-3.0/ACSSW/lib/acsexmplMount.jar:/alma/ACS-3.0/ACSSW/lib/acsexmplPowerSupplyAbeans.jar:/alma/ACS-3.0/ACSSW/lib/acsexmplPowerSupply.jar:/alma/ACS-3.0/ACSSW/lib/acsexmplRampedPowerSupplyAbeans.jar:/alma/ACS-3.0/ACSSW/lib/acsexmplRampedPowerSupply.jar:/alma/ACS-3.0/ACSSW/lib/acsjlog.jar:/alma/ACS-3.0/ACSSW/lib/acslog.jar:/alma/ACS-3.0/ACSSW/lib/acsnc.jar:/alma/ACS-3.0/ACSSW/lib/acspytest.jar:/alma/ACS-3.0/ACSSW/lib/acssamp.jar:/alma/ACS-3.0/ACSSW/lib/acsTestEntities.jar:/alma/ACS-3.0/ACSSW/lib/ACSTimeError.jar:/alma/ACS-3.0/ACSSW/lib/acstime.jar:/alma/ACS-3.0/ACSSW/lib/activation.jar:/alma/ACS-3.0/ACSSW/lib/AlmaAdminIF.jar:/alma/ACS-3.0/ACSSW/lib/AntennaNumberMap.jar:/alma/ACS-3.0/ACSSW/lib/antlr.jar:/alma/ACS-3.0/ACSSW/lib/archive_administration_if.jar:/alma/ACS-3.0/ACSSW/lib/archive_database.jar:/alma/ACS-3.0/ACSSW/lib/archive.jar:/alma/ACS-3.0/ACSSW/lib/archive_manager.jar:/alma/ACS-3.0/ACSSW/lib/ArchivePipeline.jar:/alma/ACS-3.0/ACSSW/lib/ArchivePipelineJar.jar:/alma/ACS-3.0/ACSSW/lib/archive_xmlstore_if.jar:/alma/ACS-3.0/ACSSW/lib/ArrayTime.jar:/alma/ACS-3.0/ACSSW/lib/avalon-framework.jar:/alma/ACS-3.0/ACSSW/lib/baci.jar:/alma/ACS-3.0/ACSSW/lib/bulk_stream_if.jar:/alma/ACS-3.0/ACSSW/lib/bulk_stream_store.jar:/alma/ACS-3.0/ACSSW/lib/CCC_Monitor.jar:/alma/ACS-3.0/ACSSW/lib/CDBBrowser.jar:/alma/ACS-3.0/ACSSW/lib/cdbDAL.jar:/alma/ACS-3.0/ACSSW/lib/CDB.jar:/alma/ACS-3.0/ACSSW/lib/CDP_Control.jar:/alma/ACS-3.0/ACSSW/lib/CDP_Maintenance.jar:/alma/ACS-3.0/ACSSW/lib/CDP_Monitor.jar:/alma/ACS-3.0/ACSSW/lib/ChannelStatus.jar:/alma/ACS-3.0/ACSSW/lib/commons-collections-3.0.jar:/alma/ACS-3.0/ACSSW/lib/commons-dbcp-1.1.jar:/alma/ACS-3.0/ACSSW/lib/commons-fileupload-1.0.jar:/alma/ACS-3.0/ACSSW/lib/commons-logging-1.0.1.jar:/alma/ACS-3.0/ACSSW/lib/commons-logging.jar:/alma/ACS-3.0/ACSSW/lib/commons-pool-1.1.jar:/alma/ACS-3.0/ACSSW/lib/comphelpgen.jar:/alma/ACS-3.0/ACSSW/lib/ComponentLifeCycle.jar:/alma/ACS-3.0/ACSSW/lib/concurrent.jar:/alma/ACS-3.0/ACSSW/lib/ConfigurationValidatorImpl.jar:/alma/ACS-3.0/ACSSW/lib/ConfigurationValidator.jar:/alma/ACS-3.0/ACSSW/lib/ControlEntities.jar:/alma/ACS-3.0/ACSSW/lib/ControlInterfaces.jar:/alma/ACS-3.0/ACSSW/lib/Control.jar:/alma/ACS-3.0/ACSSW/lib/CorrXMLEntity.jar:/alma/ACS-3.0/ACSSW/lib/cosyframework.jar:/alma/ACS-3.0/ACSSW/lib/cosyicons.jar:/alma/ACS-3.0/ACSSW/lib/db2jcc.jar:/alma/ACS-3.0/ACSSW/lib/db2jcc_license_cisuz.jar:/alma/ACS-3.0/ACSSW/lib/DelayServerImpl.jar:/alma/ACS-3.0/ACSSW/lib/DelayServer.jar:/alma/ACS-3.0/ACSSW/lib/diva.jar:/alma/ACS-3.0/ACSSW/lib/EventComponent.jar:/alma/ACS-3.0/ACSSW/lib/execApplications.jar:/alma/ACS-3.0/ACSSW/lib/execCommon.jar:/alma/ACS-3.0/ACSSW/lib/ExecEntities.jar:/alma/ACS-3.0/ACSSW/lib/execMaster.jar:/alma/ACS-3.0/ACSSW/lib/exist.jar:/alma/ACS-3.0/ACSSW/lib/fits.jar:/alma/ACS-3.0/ACSSW/lib/getTelCalResultsImpl.jar:/alma/ACS-3.0/ACSSW/lib/hcompress.jar:/alma/ACS-3.0/ACSSW/lib/HelloDemo.jar:/alma/ACS-3.0/ACSSW/lib/icons.jar:/alma/ACS-3.0/ACSSW/lib/image.jar:/alma/ACS-3.0/ACSSW/lib/infobus.jar:/alma/ACS-3.0/ACSSW/lib/IntegrationEvent.jar:/alma/ACS-3.0/ACSSW/lib/j2ssh-core-0.2.6.jar:/alma/ACS-3.0/ACSSW/lib/jACSCommon.jar:/alma/ACS-3.0/ACSSW/lib/jACSUtil.jar:/alma/ACS-3.0/ACSSW/lib/jai_codec.jar:/alma/ACS-3.0/ACSSW/lib/jai_core.jar:/alma/ACS-3.0/ACSSW/lib/jakarta-oro-2.0.5.jar:/alma/ACS-3.0/ACSSW/lib/jakarta-oro-2.0.6.jar:/alma/ACS-3.0/ACSSW/lib/jakarta-regexp-1.2.jar:/alma/ACS-3.0/ACSSW/lib/jaxen-full.jar:/alma/ACS-3.0/ACSSW/lib/jcchart451K.jar:/alma/ACS-3.0/ACSSW/lib/jcommon.jar:/alma/ACS-3.0/ACSSW/lib/jcontexmpl.jar:/alma/ACS-3.0/ACSSW/lib/jcont.jar:/alma/ACS-3.0/ACSSW/lib/jcontnc.jar:/alma/ACS-3.0/ACSSW/lib/jdom.jar:/alma/ACS-3.0/ACSSW/lib/jel.jar:/alma/ACS-3.0/ACSSW/lib/jfreechart.jar:/alma/ACS-3.0/ACSSW/lib/jManagerConfig.jar:/alma/ACS-3.0/ACSSW/lib/jManager.jar:/alma/ACS-3.0/ACSSW/lib/jnlp.jar:/alma/ACS-3.0/ACSSW/lib/jsky.jar:/alma/ACS-3.0/ACSSW/lib/jta.jar:/alma/ACS-3.0/ACSSW/lib/junit.jar:/alma/ACS-3.0/ACSSW/lib/jython.jar:/alma/ACS-3.0/ACSSW/lib/LampAccess.jar:/alma/ACS-3.0/ACSSW/lib/LampCallback.jar:/alma/ACS-3.0/ACSSW/lib/lc.jar:/alma/ACS-3.0/ACSSW/lib/log4j.jar:/alma/ACS-3.0/ACSSW/lib/logkit.jar:/alma/ACS-3.0/ACSSW/lib/maci.jar:/alma/ACS-3.0/ACSSW/lib/MailerIF.jar:/alma/ACS-3.0/ACSSW/lib/mail.jar:/alma/ACS-3.0/ACSSW/lib/Maintenance.jar:/alma/ACS-3.0/ACSSW/lib/mlibwrapper_jai.jar:/alma/ACS-3.0/ACSSW/lib/monitor_stream_if.jar:/alma/ACS-3.0/ACSSW/lib/monitor_stream_store.jar:/alma/ACS-3.0/ACSSW/lib/ObservationControl.jar:/alma/ACS-3.0/ACSSW/lib/ObservationQuery.jar:/alma/ACS-3.0/ACSSW/lib/ObservingTool.jar:/alma/ACS-3.0/ACSSW/lib/ObsPrepEntities.jar:/alma/ACS-3.0/ACSSW/lib/oe.jar:/alma/ACS-3.0/ACSSW/lib/OffLineOperation.jar:/alma/ACS-3.0/ACSSW/lib/openorb13Debug.jar:/alma/ACS-3.0/ACSSW/lib/OperatorIF.jar:/alma/ACS-3.0/ACSSW/lib/otproto.jar:/alma/ACS-3.0/ACSSW/lib/ParameterTuning.jar:/alma/ACS-3.0/ACSSW/lib/PathCorrResult.jar:/alma/ACS-3.0/ACSSW/lib/prevayler-1.02.001.jar:/alma/ACS-3.0/ACSSW/lib/ProjectRepositoryIF.jar:/alma/ACS-3.0/ACSSW/lib/ProjectRepository.jar:/alma/ACS-3.0/ACSSW/lib/QlPipeline.jar:/alma/ACS-3.0/ACSSW/lib/QlPipelineJar.jar:/alma/ACS-3.0/ACSSW/lib/QuickLookEntities.jar:/alma/ACS-3.0/ACSSW/lib/resolver-20030708.jar:/alma/ACS-3.0/ACSSW/lib/saxpath.jar:/alma/ACS-3.0/ACSSW/lib/SchedPlanningModeSimulator.jar:/alma/ACS-3.0/ACSSW/lib/SchedulingEntities.jar:/alma/ACS-3.0/ACSSW/lib/SchedulingInterfaces.jar:/alma/ACS-3.0/ACSSW/lib/scheduling.jar:/alma/ACS-3.0/ACSSW/lib/SciencePipeline.jar:/alma/ACS-3.0/ACSSW/lib/SciencePipelineJar.jar:/alma/ACS-3.0/ACSSW/lib/SecurityIF.jar:/alma/ACS-3.0/ACSSW/lib/servlet.jar:/alma/ACS-3.0/ACSSW/lib/skinlf.jar:/alma/ACS-3.0/ACSSW/lib/SubArray.jar:/alma/ACS-3.0/ACSSW/lib/systementities.jar:/alma/ACS-3.0/ACSSW/lib/tablelayout.jar:/alma/ACS-3.0/ACSSW/lib/telcalArchiveImpl.jar:/alma/ACS-3.0/ACSSW/lib/TelCalArchive.jar:/alma/ACS-3.0/ACSSW/lib/TelCalEntities.jar:/alma/ACS-3.0/ACSSW/lib/TelCalPublisher.jar:/alma/ACS-3.0/ACSSW/lib/TelCalReceivedNC.jar:/alma/ACS-3.0/ACSSW/lib/TelCalResults.jar:/alma/ACS-3.0/ACSSW/lib/UserAdminIF.jar:/alma/ACS-3.0/ACSSW/lib/UserAdminPrivilegedIF.jar:/alma/ACS-3.0/ACSSW/lib/UserInfoIF.jar:/alma/ACS-3.0/ACSSW/lib/util.jar:/alma/ACS-3.0/ACSSW/lib/xindice-1.1b.jar:/alma/ACS-3.0/ACSSW/lib/XmlComponent.jar:/alma/ACS-3.0/ACSSW/lib/xmldb.jar:/alma/ACS-3.0/ACSSW/lib/xmlentity.jar:/alma/ACS-3.0/ACSSW/lib/xmlidl.jar:/alma/ACS-3.0/ACSSW/lib/xmljbind.jar:/alma/ACS-3.0/ACSSW/lib/xmlrpc-1.2.jar:/alma/ACS-3.0/JacORB/lib/jacorb.jar:/alma/ACS-3.0/JacORB/lib/idl.jar::/alma/ACS-3.0/acsdata/config/abeans/Config:/alma/ACS-3.0/acsdata/config -Djava.endorsed.dirs=/alma/ACS-3.0/ACSSW/lib/endorsed: -Dorg.omg.CORBA.ORBClass=org.jacorb.orb.ORB -Dorg.omg.CORBA.ORBSingletonClass=org.jacorb.orb.ORBSingleton -Duser.timezone=UTC -Dabeans.home=/alma/ACS-3.0/acsdata/config/abeans/Config -DACS.manager=corbaloc::te49:3000/Manager -DORBInitRef.NameService=corbaloc::te49:3001/NameService -DACS.repository=corbaloc::te49:3004/InterfaceRepository -DACS.tmp=/alma/ACS-3.0/acsdata/tmp/ACS_INSTANCE.0 -DACS.baseport=0 -Dalma.MicroArchive.DbDir=/alma/ACS-3.0/acsdata/config/MicroArchive alma.acs.container.AcsContainerRunner -containerName frodoContainer -OAport 3050
Jun 4, 2004 11:35:52 AM alma.acs.container.AcsContainerRunner run
INFO: AcsContainerRunner#run
Jun 4, 2004 11:35:52 AM alma.acs.container.corba.AcsCorba initCorba
FINER: ENTRY
Jun 4, 2004 11:35:52 AM alma.acs.container.corba.AcsCorba initCorba
FINER: ORB options -containerName frodoContainer -OAport 3050 ORB properties: {org.omg.CORBA.ORBClass=org.jacorb.orb.ORB, OAPort=3050, org.omg.CORBA.ORBSingletonClass=org.jacorb.orb.ORBSingleton}
JacORB V 1.4.1, www.jacorb.org
(C) Gerald Brose, FU Berlin/XTRADYNE Technologies, July 2002
Jun 4, 2004 11:35:52 AM alma.acs.container.corba.AcsCorba initCorba
FINER: ORB initialized.
[ POA RootPOA - ready ]
Jun 4, 2004 11:35:53 AM alma.acs.container.corba.AcsCorba initRootPOA
FINEST: RootPOA initialized.
[ POA ContainerPOA - ready ]
Jun 4, 2004 11:35:53 AM alma.acs.container.corba.AcsCorba initPOAForContainer
FINEST: ContainerPOA created.
[ POA ComponentPOA - ready ]
Jun 4, 2004 11:35:53 AM alma.acs.container.corba.AcsCorba initPOAForComponents
FINEST: ComponentPOA created.
Jun 4, 2004 11:35:53 AM alma.acs.container.corba.AcsCorba initCorba
FINER: RETURN
Jun 4, 2004 11:35:53 AM alma.acs.container.AcsManagerProxy getManager
FINE: Manager reference not available. Trying to resolve...
Jun 4, 2004 11:35:53 AM alma.acs.container.AcsManagerProxy findManager
FINEST: manager corbaloc 'corbaloc::te49:3000/Manager' resolved.
[ Connected to 134.171.12.100:3000 ]
Jun 4, 2004 11:35:53 AM alma.acs.container.AcsManagerProxy findManager
FINEST: manager narrow successful.
Jun 4, 2004 11:35:53 AM alma.acs.container.AcsContainerRunner createContainer
FINE: creating the AcsContainer frodoContainer
Jun 4, 2004 11:35:53 AM alma.acs.container.corba.AcsCorba activateContainer
FINER: entering activateContainer name=frodoContainer
Jun 4, 2004 11:35:53 AM alma.acs.container.AcsContainer registerWithCorba
FINER: AcsContainer successfully registered with the ORB as a Container
Jun 4, 2004 11:35:53 AM alma.acs.container.AcsContainerRunner createContainer
FINE: AcsContainer 'frodoContainer' created.
Jun 4, 2004 11:35:53 AM alma.acs.container.AcsContainerRunner initAcsLogging
FINE: init ACS logging...
[ Connected to 134.171.12.100:3003 ]
Jun 4, 2004 11:35:53 AM alma.acs.logging.ClientLogManager init
FINE: CORBA logging service handle obtained.
Jun 4, 2004 11:35:53 AM alma.acs.container.AcsContainerRunner initAcsLogging
FINE: done init ACS logging
Jun 4, 2004 11:35:53 AM alma.acs.container.AcsContainer name
FINE: call to name() answered with 'frodoContainer'.
Jun 4, 2004 11:35:53 AM alma.acs.container.AcsContainer authenticate
FINE: call to authenticate() answered with 'A frodoContainer'.
Jun 4, 2004 11:35:53 AM alma.acs.container.AcsContainer name
FINE: call to name() answered with 'frodoContainer'.
Jun 4, 2004 11:35:53 AM alma.acs.container.AcsManagerProxy loginToManager
FINE: Manager login done, handle '73007105' obtained.
Jun 4, 2004 11:35:53 AM alma.acs.container.AcsContainerRunner run
FINE: entering orb loop
Jun 4, 2004 11:35:53 AM alma.acs.util.StopWatch logLapTime
FINE: elapsed time in ms to start the container: 1077
Jun 4, 2004 11:35:56 AM alma.acs.container.AcsContainer message
INFO: Info message from the manager: Startup statistics: 7 components queued to be activated.
Jun 4, 2004 11:35:56 AM alma.acs.container.AcsContainer activate_component
INFO: <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<
Jun 4, 2004 11:35:56 AM alma.acs.container.AcsContainer activate_component
INFO: activate_component: handle=16777217 name=GETTELCALRESULTS1 helperClass=alma.TelCalPublisher.GetTelCalResultsImpl.GetTelCalResultsHelper type=IDL:alma/TelCalPublisher/GetTelCalResults:1.0
Jun 4, 2004 11:35:56 AM alma.acs.container.AcsContainer createComponentHelper
FINER: creating component helper instance of type alma.TelCalPublisher.GetTelCalResultsImpl.GetTelCalResultsHelper
Jun 4, 2004 11:35:56 AM alma.acs.container.ComponentHelper getComponentImpl
FINER: component implementation class 'alma.TelCalPublisher.GetTelCalResultsImpl.GetTelCalResultsImpl' instantiated.
Jun 4, 2004 11:35:56 AM alma.acs.container.AcsContainer activate_component
FINER: component GETTELCALRESULTS1 implements operations interface directly; no dynamic translator proxy used.
[ POA ComponentPOAGETTELCALRESULTS1 - ready ]
Jun 4, 2004 11:35:56 AM alma.acs.container.ComponentAdapter activateComponent
FINER: entering ComponentAdapter#activateComponent for GETTELCALRESULTS1
Jun 4, 2004 11:35:56 AM alma.acs.container.corba.AcsCorba activateComponent
FINER: entering activateComponent: name=GETTELCALRESULTS1
Jun 4, 2004 11:35:56 AM alma.acs.container.corba.AcsCorba activateComponent
FINER: component 'GETTELCALRESULTS1' activated as CORBA object.
Jun 4, 2004 11:35:56 AM alma.acs.container.ComponentAdapter _getInterfaces
FINE: interfaces of component 'GETTELCALRESULTS1': IDL:alma/TelCalPublisher/GetTelCalResults:1.0IDL:alma/ACS/ACSComponent:1.0IDL:omg.org/CORBA/Object:1.0
Jun 4, 2004 11:35:56 AM alma.acs.container.AcsContainer activate_component
FINE: about to initialize component GETTELCALRESULTS1
Jun 4, 2004 11:35:56 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component GETTELCALRESULTS1 from NEW to INITIALIZING
Jun 4, 2004 11:35:56 AM alma.acs.logging.ClientLogManager getLoggerForComponent
FINE: a logger for component .GETTELCALRESULTS1 is available.
Jun 4, 2004 11:35:56 AM alma.TelCalPublisher.GetTelCalResultsImpl.GetTelCalResultsImpl initialize
INFO: initialize() called...
Jun 4, 2004 11:35:56 AM alma.acs.container.ContainerServicesImpl getComponent
FINE: will retrieve remote component 'ARCHIVE_CONNECTION' using ACS Manager#get_component
Jun 4, 2004 11:35:56 AM alma.acs.container.AcsContainer activate_component
INFO: <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<
Jun 4, 2004 11:35:56 AM alma.acs.container.AcsContainer activate_component
INFO: activate_component: handle=16777218 name=ARCHIVE_CONNECTION helperClass=alma.archive.helpers.ArchiveConnectionHelper type=IDL:alma/xmlstore/ArchiveConnection:1.0
Jun 4, 2004 11:35:56 AM alma.acs.container.AcsContainer createComponentHelper
FINER: creating component helper instance of type alma.archive.helpers.ArchiveConnectionHelper
Jun 4, 2004 11:35:57 AM alma.acs.container.ComponentHelper getComponentImpl
FINER: component implementation class 'alma.archive.components.ArchiveConnectionImpl' instantiated.
Jun 4, 2004 11:35:57 AM alma.acs.container.AcsContainer activate_component
FINER: component ARCHIVE_CONNECTION implements operations interface directly; no dynamic translator proxy used.
[ POA ComponentPOAARCHIVE_CONNECTION - ready ]
Jun 4, 2004 11:35:57 AM alma.acs.container.ComponentAdapter activateComponent
FINER: entering ComponentAdapter#activateComponent for ARCHIVE_CONNECTION
Jun 4, 2004 11:35:57 AM alma.acs.container.corba.AcsCorba activateComponent
FINER: entering activateComponent: name=ARCHIVE_CONNECTION
Jun 4, 2004 11:35:57 AM alma.acs.container.corba.AcsCorba activateComponent
FINER: component 'ARCHIVE_CONNECTION' activated as CORBA object.
Jun 4, 2004 11:35:57 AM alma.acs.container.ComponentAdapter _getInterfaces
FINE: interfaces of component 'ARCHIVE_CONNECTION': IDL:alma/xmlstore/ArchiveConnection:1.0IDL:omg.org/CORBA/Object:1.0
Jun 4, 2004 11:35:57 AM alma.acs.container.AcsContainer activate_component
FINE: about to initialize component ARCHIVE_CONNECTION
Jun 4, 2004 11:35:57 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component ARCHIVE_CONNECTION from NEW to INITIALIZING
Jun 4, 2004 11:35:57 AM alma.acs.logging.ClientLogManager getLoggerForComponent
FINE: a logger for component .ARCHIVE_CONNECTION is available.
Jun 4, 2004 11:35:57 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component ARCHIVE_CONNECTION from INITIALIZING to INITIALIZED
Jun 4, 2004 11:35:57 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component ARCHIVE_CONNECTION from INITIALIZED to OPERATIONAL
Jun 4, 2004 11:35:57 AM alma.acs.container.AcsContainer activate_component
INFO: component ARCHIVE_CONNECTION activated and initialized in 42 ms.
Jun 4, 2004 11:35:57 AM alma.acs.container.AcsContainer activate_component
INFO: >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
Jun 4, 2004 11:35:57 AM alma.acs.container.ContainerServicesImpl getComponent
FINE: component ARCHIVE_CONNECTION retrieved successfully.
Jun 4, 2004 11:35:57 AM alma.acs.container.ContainerSealant invoke
INFO: intercepted a call to 'ARCHIVE_CONNECTION#getOperational'...
Jun 4, 2004 11:35:57 AM alma.archive.database.helpers.DBConfiguration searchConfigFile
WARNING: Property archive.config.file not set, using default config file: /alma/ACS-3.0/acsdata/config/dbConfig.xml
Jun 4, 2004 11:35:57 AM alma.archive.database.helpers.DBConfiguration readConfig
INFO: Setting database configuration.
Jun 4, 2004 11:35:57 AM alma.archive.database.helpers.DBConfiguration readConfig
INFO: Using database backend xindice
Jun 4, 2004 11:35:57 AM alma.archive.database.helpers.DBConfiguration searchConfigFile
WARNING: Property archive.config.file not set, using default config file: /alma/ACS-3.0/acsdata/config/dbConfig.xml
Jun 4, 2004 11:35:57 AM alma.archive.database.helpers.DBConfiguration readConfig
INFO: Setting database configuration.
Jun 4, 2004 11:35:57 AM alma.archive.database.helpers.DBConfiguration readConfig
INFO: Using database backend xindice
Jun 4, 2004 11:35:57 AM alma.archive.database.xmldb.XmldbConnector setLocation
INFO: Xindice home set to /alma/ACS-3.0/acsdata/config/MicroArchive
0 [RequestProcessor-5] INFO client.embed - No configuration file specified, going with the default configuration
144 [RequestProcessor-5] INFO client.embed - Database name: 'db'
144 [RequestProcessor-5] INFO xindice.core - Database points to /alma/ACS-3.0/acsdata/config/MicroArchive/./db
Jun 4, 2004 11:35:58 AM alma.archive.database.helpers.DBConfiguration searchConfigFile
WARNING: Property archive.config.file not set, using default config file: /alma/ACS-3.0/acsdata/config/dbConfig.xml
Jun 4, 2004 11:35:58 AM alma.archive.database.helpers.DBConfiguration readConfig
INFO: Setting database configuration.
Jun 4, 2004 11:35:58 AM alma.archive.database.helpers.DBConfiguration readConfig
INFO: Using database backend xindice
Jun 4, 2004 11:35:58 AM alma.archive.components.OperationalImpl connect
INFO: Connected to the internal interface
Jun 4, 2004 11:35:58 AM alma.acs.logging.ClientLogManager getLoggerForComponent
FINE: a logger for component .ARCHIVE_CONNECTION is available.
Jun 4, 2004 11:35:58 AM alma.archive.commands.ListenerManager load
INFO: ARCHIVE: Problem retriving the string
Jun 4, 2004 11:35:58 AM alma.archive.components.OperationalImpl connect
INFO: Connected to the Liatener Manager
Jun 4, 2004 11:35:58 AM alma.archive.commands.PathManager load
INFO: ARCHIVE: Problem retriving the string
Jun 4, 2004 11:35:58 AM alma.acs.container.corba.AcsCorba createPOAForOffshoots
FINEST: will have to create offshoot POA
[ POA offshootPoa - ready ]
Jun 4, 2004 11:35:58 AM alma.acs.container.corba.AcsCorba createPOAForOffshoots
FINEST: successfully created offshoot POA
Jun 4, 2004 11:35:58 AM alma.acs.container.corba.AcsCorba activateOffShoot
FINER: offshoot of type 'alma.archive.components.OperationalImpl' activated as a CORBA object.
Jun 4, 2004 11:35:58 AM alma.acs.container.ContainerSealant invoke
INFO: returning from ARCHIVE_CONNECTION#getOperational after 1034 ms.
JacORB V 1.4.1, www.jacorb.org
(C) Gerald Brose, FU Berlin/XTRADYNE Technologies, July 2002
[ POA RootPOA - ready ]
[ Connected to 134.171.12.100:3001 ]
[ Connected to 134.171.12.100:3001 ]
Attempting to create the TelCalPublisherEventNC channel.
[ Connected to 134.171.12.100:3002 ]
Jun 4, 2004 11:35:58 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component GETTELCALRESULTS1 from INITIALIZING to INITIALIZED
Jun 4, 2004 11:35:58 AM alma.TelCalPublisher.GetTelCalResultsImpl.GetTelCalResultsImpl execute
INFO: execute() called...
Jun 4, 2004 11:35:58 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component GETTELCALRESULTS1 from INITIALIZED to OPERATIONAL
Jun 4, 2004 11:35:58 AM alma.acs.container.AcsContainer activate_component
INFO: component GETTELCALRESULTS1 activated and initialized in 1576 ms.
Jun 4, 2004 11:35:58 AM alma.acs.container.AcsContainer activate_component
INFO: >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
Jun 4, 2004 11:35:58 AM alma.acs.container.AcsContainer activate_component
INFO: <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<
Jun 4, 2004 11:35:58 AM alma.acs.container.AcsContainer activate_component
INFO: activate_component: handle=16777219 name=IDENTIFIER_ARCHIVE helperClass=alma.archive.helpers.IdentifierHelper type=IDL:alma/xmlstore/Identifier:1.0
Jun 4, 2004 11:35:58 AM alma.acs.container.AcsContainer createComponentHelper
FINER: creating component helper instance of type alma.archive.helpers.IdentifierHelper
Jun 4, 2004 11:35:58 AM alma.acs.container.ComponentHelper getComponentImpl
FINER: component implementation class 'alma.archive.components.IdentifierImpl' instantiated.
Jun 4, 2004 11:35:58 AM alma.acs.container.AcsContainer activate_component
FINER: component IDENTIFIER_ARCHIVE implements operations interface directly; no dynamic translator proxy used.
[ POA ComponentPOAIDENTIFIER_ARCHIVE - ready ]
Jun 4, 2004 11:35:58 AM alma.acs.container.ComponentAdapter activateComponent
FINER: entering ComponentAdapter#activateComponent for IDENTIFIER_ARCHIVE
Jun 4, 2004 11:35:58 AM alma.acs.container.corba.AcsCorba activateComponent
FINER: entering activateComponent: name=IDENTIFIER_ARCHIVE
Jun 4, 2004 11:35:58 AM alma.acs.container.corba.AcsCorba activateComponent
FINER: component 'IDENTIFIER_ARCHIVE' activated as CORBA object.
Jun 4, 2004 11:35:58 AM alma.acs.container.ComponentAdapter _getInterfaces
FINE: interfaces of component 'IDENTIFIER_ARCHIVE': IDL:alma/xmlstore/Identifier:1.0IDL:alma/ACS/ACSComponent:1.0IDL:omg.org/CORBA/Object:1.0
Jun 4, 2004 11:35:58 AM alma.acs.container.AcsContainer activate_component
FINE: about to initialize component IDENTIFIER_ARCHIVE
Jun 4, 2004 11:35:58 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component IDENTIFIER_ARCHIVE from NEW to INITIALIZING
Jun 4, 2004 11:35:58 AM alma.acs.logging.ClientLogManager getLoggerForComponent
FINE: a logger for component .IDENTIFIER_ARCHIVE is available.
Jun 4, 2004 11:35:58 AM alma.archive.components.IdentifierImpl connect
INFO: Connecting to the identifier manager
Jun 4, 2004 11:35:58 AM alma.archive.database.helpers.DBConfiguration searchConfigFile
WARNING: Property archive.config.file not set, using default config file: /alma/ACS-3.0/acsdata/config/dbConfig.xml
Jun 4, 2004 11:35:58 AM alma.archive.database.helpers.DBConfiguration readConfig
INFO: Setting database configuration.
Jun 4, 2004 11:35:58 AM alma.archive.database.helpers.DBConfiguration readConfig
INFO: Using database backend xindice
Jun 4, 2004 11:35:58 AM alma.archive.components.IdentifierImpl connect
INFO: Connected to the identifier manager
Jun 4, 2004 11:35:58 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component IDENTIFIER_ARCHIVE from INITIALIZING to INITIALIZED
Jun 4, 2004 11:35:58 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component IDENTIFIER_ARCHIVE from INITIALIZED to OPERATIONAL
Jun 4, 2004 11:35:58 AM alma.acs.container.AcsContainer activate_component
INFO: component IDENTIFIER_ARCHIVE activated and initialized in 39 ms.
Jun 4, 2004 11:35:58 AM alma.acs.container.AcsContainer activate_component
INFO: >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
Jun 4, 2004 11:35:58 AM alma.acs.container.AcsContainer activate_component
INFO: <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<
Jun 4, 2004 11:35:58 AM alma.acs.container.AcsContainer activate_component
INFO: activate_component: handle=16777220 name=ControlSystem1 helperClass=alma.Control.masterController.ControlSystemHelper type=IDL:alma/Control/ControlSystem:1.0
Jun 4, 2004 11:35:58 AM alma.acs.container.AcsContainer createComponentHelper
FINER: creating component helper instance of type alma.Control.masterController.ControlSystemHelper
Jun 4, 2004 11:35:58 AM alma.acs.container.ComponentHelper getComponentImpl
FINER: component implementation class 'alma.Control.masterController.ControlSystemImpl' instantiated.
Jun 4, 2004 11:35:58 AM alma.acs.container.AcsContainer activate_component
FINER: component ControlSystem1 implements operations interface directly; no dynamic translator proxy used.
[ POA ComponentPOAControlSystem1 - ready ]
Jun 4, 2004 11:35:58 AM alma.acs.container.ComponentAdapter activateComponent
FINER: entering ComponentAdapter#activateComponent for ControlSystem1
Jun 4, 2004 11:35:58 AM alma.acs.container.corba.AcsCorba activateComponent
FINER: entering activateComponent: name=ControlSystem1
Jun 4, 2004 11:35:58 AM alma.acs.container.corba.AcsCorba activateComponent
FINER: component 'ControlSystem1' activated as CORBA object.
Jun 4, 2004 11:35:58 AM alma.acs.container.ComponentAdapter _getInterfaces
FINE: interfaces of component 'ControlSystem1': IDL:alma/Control/ControlSystem:1.0IDL:alma/ACS/ACSComponent:1.0IDL:alma/Control/ControlStatus:1.0IDL:omg.org/CORBA/Object:1.0
Jun 4, 2004 11:35:58 AM alma.acs.container.AcsContainer activate_component
FINE: about to initialize component ControlSystem1
Jun 4, 2004 11:35:58 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component ControlSystem1 from NEW to INITIALIZING
Jun 4, 2004 11:35:58 AM alma.acs.logging.ClientLogManager getLoggerForComponent
FINE: a logger for component .ControlSystem1 is available.
Jun 4, 2004 11:35:58 AM alma.Control.masterController.ControlStatusImpl initialize
INFO: initialize() called...
Attempting to create the CONTROLSYSTEM channel.
Jun 4, 2004 11:35:58 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component ControlSystem1 from INITIALIZING to INITIALIZED
Jun 4, 2004 11:35:58 AM alma.Control.masterController.ControlStatusImpl execute
INFO: execute() called...
Jun 4, 2004 11:35:58 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component ControlSystem1 from INITIALIZED to OPERATIONAL
Jun 4, 2004 11:35:58 AM alma.acs.container.AcsContainer activate_component
INFO: component ControlSystem1 activated and initialized in 88 ms.
Jun 4, 2004 11:35:58 AM alma.acs.container.AcsContainer activate_component
INFO: >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
Jun 4, 2004 11:35:58 AM alma.acs.container.AcsContainer activate_component
INFO: <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<
Jun 4, 2004 11:35:58 AM alma.acs.container.AcsContainer activate_component
INFO: activate_component: handle=16777221 name=SCIENCE_PIPELINE helperClass=alma.pipelinescience.components.SciencePipelineHelper type=IDL:alma/pipelinescience/SciencePipeline:1.0
Jun 4, 2004 11:35:58 AM alma.acs.container.AcsContainer createComponentHelper
FINER: creating component helper instance of type alma.pipelinescience.components.SciencePipelineHelper
Jun 4, 2004 11:35:58 AM alma.acs.container.ComponentHelper getComponentImpl
FINER: component implementation class 'alma.pipelinescience.components.SciencePipelineImpl' instantiated.
Jun 4, 2004 11:35:58 AM alma.acs.container.AcsContainer activate_component
FINER: component SCIENCE_PIPELINE implements operations interface directly; no dynamic translator proxy used.
[ POA ComponentPOASCIENCE_PIPELINE - ready ]
Jun 4, 2004 11:35:58 AM alma.acs.container.ComponentAdapter activateComponent
FINER: entering ComponentAdapter#activateComponent for SCIENCE_PIPELINE
Jun 4, 2004 11:35:58 AM alma.acs.container.corba.AcsCorba activateComponent
FINER: entering activateComponent: name=SCIENCE_PIPELINE
Jun 4, 2004 11:35:58 AM alma.acs.container.corba.AcsCorba activateComponent
FINER: component 'SCIENCE_PIPELINE' activated as CORBA object.
Jun 4, 2004 11:35:58 AM alma.acs.container.ComponentAdapter _getInterfaces
FINE: interfaces of component 'SCIENCE_PIPELINE': IDL:alma/pipelinescience/SciencePipeline:1.0IDL:alma/ACS/ACSComponent:1.0IDL:omg.org/CORBA/Object:1.0
Jun 4, 2004 11:35:58 AM alma.acs.container.AcsContainer activate_component
FINE: about to initialize component SCIENCE_PIPELINE
Jun 4, 2004 11:35:58 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component SCIENCE_PIPELINE from NEW to INITIALIZING
Jun 4, 2004 11:35:58 AM alma.acs.logging.ClientLogManager getLoggerForComponent
FINE: a logger for component .SCIENCE_PIPELINE is available.
Jun 4, 2004 11:35:58 AM alma.pipelinescience.components.SciencePipelineImpl initialize
INFO: SCIENCE_PIPELINE: initialize() method called
Jun 4, 2004 11:35:58 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component SCIENCE_PIPELINE from INITIALIZING to INITIALIZED
Jun 4, 2004 11:35:58 AM alma.pipelinescience.components.SciencePipelineImpl execute
INFO: SCIENCE_PIPELINE: execute() method called
Jun 4, 2004 11:35:58 AM alma.acs.container.ContainerServicesImpl getComponent
FINE: will retrieve remote component 'IDENTIFIER_ARCHIVE' using ACS Manager#get_component
Jun 4, 2004 11:35:58 AM alma.acs.container.ContainerServicesImpl getComponent
FINE: component IDENTIFIER_ARCHIVE retrieved successfully.
Jun 4, 2004 11:35:58 AM alma.acs.container.ContainerServicesImpl getComponent
FINE: will retrieve remote component 'ARCHIVE_CONNECTION' using ACS Manager#get_component
Jun 4, 2004 11:35:58 AM alma.acs.container.ContainerServicesImpl getComponent
FINE: component ARCHIVE_CONNECTION retrieved successfully.
Jun 4, 2004 11:35:58 AM alma.acs.container.ContainerSealant invoke
INFO: intercepted a call to 'ARCHIVE_CONNECTION#getOperational'...
Jun 4, 2004 11:35:58 AM alma.archive.components.OperationalImpl connect
INFO: Connected to the internal interface
Jun 4, 2004 11:35:58 AM alma.archive.components.OperationalImpl connect
INFO: Connected to the Liatener Manager
Jun 4, 2004 11:35:58 AM alma.acs.container.corba.AcsCorba activateOffShoot
FINER: offshoot of type 'alma.archive.components.OperationalImpl' activated as a CORBA object.
Jun 4, 2004 11:35:58 AM alma.acs.container.ContainerSealant invoke
INFO: returning from ARCHIVE_CONNECTION#getOperational after 8 ms.
Attempting to create the sciencepipeline channel.
Jun 4, 2004 11:35:58 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component SCIENCE_PIPELINE from INITIALIZED to OPERATIONAL
Jun 4, 2004 11:35:58 AM alma.acs.container.AcsContainer activate_component
INFO: component SCIENCE_PIPELINE activated and initialized in 96 ms.
Jun 4, 2004 11:35:58 AM alma.acs.container.AcsContainer activate_component
INFO: >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
Jun 4, 2004 11:35:58 AM alma.acs.container.AcsContainer activate_component
INFO: <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<
Jun 4, 2004 11:35:58 AM alma.acs.container.AcsContainer activate_component
INFO: activate_component: handle=16777222 name=CONFIGURATION_VALIDATOR helperClass=alma.Correlator.ConfigurationValidatorImpl.ConfigurationValidatorHelper type=IDL:alma/Correlator/ConfigurationValidator:1.0
Jun 4, 2004 11:35:58 AM alma.acs.container.AcsContainer createComponentHelper
FINER: creating component helper instance of type alma.Correlator.ConfigurationValidatorImpl.ConfigurationValidatorHelper
Jun 4, 2004 11:35:58 AM alma.acs.container.ComponentHelper getComponentImpl
FINER: component implementation class 'alma.Correlator.ConfigurationValidatorImpl.ConfigurationValidatorImpl' instantiated.
Jun 4, 2004 11:35:58 AM alma.acs.container.AcsContainer activate_component
FINER: creating dynamic proxy to map corba interface calls to component CONFIGURATION_VALIDATOR.
Jun 4, 2004 11:35:59 AM alma.acs.util.StopWatch logLapTime
FINE: elapsed time in ms to verify automatic translation for methods in alma.Correlator.ConfigurationValidatorOperations: 114
[ POA ComponentPOACONFIGURATION_VALIDATOR - ready ]
Jun 4, 2004 11:35:59 AM alma.acs.container.ComponentAdapter activateComponent
FINER: entering ComponentAdapter#activateComponent for CONFIGURATION_VALIDATOR
Jun 4, 2004 11:35:59 AM alma.acs.container.corba.AcsCorba activateComponent
FINER: entering activateComponent: name=CONFIGURATION_VALIDATOR
Jun 4, 2004 11:35:59 AM alma.acs.container.corba.AcsCorba activateComponent
FINER: component 'CONFIGURATION_VALIDATOR' activated as CORBA object.
Jun 4, 2004 11:35:59 AM alma.acs.container.ComponentAdapter _getInterfaces
FINE: interfaces of component 'CONFIGURATION_VALIDATOR': IDL:alma/Correlator/ConfigurationValidator:1.0IDL:alma/ACS/ACSComponent:1.0IDL:omg.org/CORBA/Object:1.0
Jun 4, 2004 11:35:59 AM alma.acs.container.AcsContainer activate_component
FINE: about to initialize component CONFIGURATION_VALIDATOR
Jun 4, 2004 11:35:59 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component CONFIGURATION_VALIDATOR from NEW to INITIALIZING
Jun 4, 2004 11:35:59 AM alma.acs.logging.ClientLogManager getLoggerForComponent
FINE: a logger for component .CONFIGURATION_VALIDATOR is available.
Jun 4, 2004 11:35:59 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component CONFIGURATION_VALIDATOR from INITIALIZING to INITIALIZED
Jun 4, 2004 11:35:59 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component CONFIGURATION_VALIDATOR from INITIALIZED to OPERATIONAL
Jun 4, 2004 11:35:59 AM alma.acs.container.AcsContainer activate_component
INFO: component CONFIGURATION_VALIDATOR activated and initialized in 171 ms.
Jun 4, 2004 11:35:59 AM alma.acs.container.AcsContainer activate_component
INFO: >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
Jun 4, 2004 11:35:59 AM alma.acs.container.AcsContainer activate_component
INFO: <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<
Jun 4, 2004 11:35:59 AM alma.acs.container.AcsContainer activate_component
INFO: activate_component: handle=16777223 name=BULKSTORE helperClass=alma.archive.bulkstream.BulkStreamHelper type=IDL:alma/bulkstream/BulkStreamListener:1.0
Jun 4, 2004 11:35:59 AM alma.acs.container.AcsContainer createComponentHelper
FINER: creating component helper instance of type alma.archive.bulkstream.BulkStreamHelper
Attempting to create the bulkstore channel.
Jun 4, 2004 11:35:59 AM alma.acs.container.ComponentHelper getComponentImpl
FINER: component implementation class 'alma.archive.bulkstream.BulkStreamStore' instantiated.
Jun 4, 2004 11:35:59 AM alma.acs.container.AcsContainer activate_component
FINER: component BULKSTORE implements operations interface directly; no dynamic translator proxy used.
[ POA ComponentPOABULKSTORE - ready ]
Jun 4, 2004 11:35:59 AM alma.acs.container.ComponentAdapter activateComponent
FINER: entering ComponentAdapter#activateComponent for BULKSTORE
Jun 4, 2004 11:35:59 AM alma.acs.container.corba.AcsCorba activateComponent
FINER: entering activateComponent: name=BULKSTORE
Jun 4, 2004 11:35:59 AM alma.acs.container.corba.AcsCorba activateComponent
FINER: component 'BULKSTORE' activated as CORBA object.
Jun 4, 2004 11:35:59 AM alma.acs.container.ComponentAdapter _getInterfaces
FINE: interfaces of component 'BULKSTORE': IDL:alma/bulkstream/BulkStreamListener:1.0IDL:alma/ACS/ACSComponent:1.0IDL:omg.org/CORBA/Object:1.0
Jun 4, 2004 11:35:59 AM alma.acs.container.AcsContainer activate_component
FINE: about to initialize component BULKSTORE
Jun 4, 2004 11:35:59 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component BULKSTORE from NEW to INITIALIZING
Jun 4, 2004 11:35:59 AM alma.acs.logging.ClientLogManager getLoggerForComponent
FINE: a logger for component .BULKSTORE is available.
Jun 4, 2004 11:35:59 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component BULKSTORE from INITIALIZING to INITIALIZED
Jun 4, 2004 11:35:59 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component BULKSTORE from INITIALIZED to OPERATIONAL
Jun 4, 2004 11:35:59 AM alma.acs.container.AcsContainer activate_component
INFO: component BULKSTORE activated and initialized in 266 ms.
Jun 4, 2004 11:35:59 AM alma.acs.container.AcsContainer activate_component
INFO: >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
Jun 4, 2004 11:35:59 AM alma.acs.container.AcsContainer message
INFO: Info message from the manager: Startup statistics: 7 of 7 components activated.
Suppler.subscription_change(...) the 'CONTROLSYSTEM' channel: override to do something useful.
Suppler.subscription_change(...) the 'CONTROLSYSTEM' channel: override to do something useful.
Suppler.subscription_change(...) the 'CONTROLSYSTEM' channel: override to do something useful.
Jun 4, 2004 11:36:09 AM alma.acs.container.ContainerSealant invoke
INFO: intercepted a call to 'BULKSTORE#startListener'...
Jun 4, 2004 11:36:09 AM alma.archive.bulkstream.BulkStreamStore startListener
INFO: Bulk listener up and waiting... Using single thread
Jun 4, 2004 11:36:09 AM alma.acs.container.ContainerSealant invoke
INFO: returning from BULKSTORE#startListener after 247 ms.
Jun 4, 2004 11:36:23 AM alma.acs.container.AcsContainer ping
FINER: ping received.
Jun 4, 2004 11:36:53 AM alma.acs.container.AcsContainer ping
FINER: ping received.
Jun 4, 2004 11:37:23 AM alma.acs.container.AcsContainer ping
FINER: ping received.
Jun 4, 2004 11:37:53 AM alma.acs.container.AcsContainer ping
FINER: ping received.
Jun 4, 2004 11:38:19 AM alma.acs.container.AcsContainer activate_component
INFO: <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<
Jun 4, 2004 11:38:19 AM alma.acs.container.AcsContainer activate_component
INFO: activate_component: handle=16777229 name=MASTER_SCHEDULER helperClass=alma.scheduling.master_scheduler.MSHelper type=IDL:alma/scheduling/MS:1.0
Jun 4, 2004 11:38:19 AM alma.acs.container.AcsContainer createComponentHelper
FINER: creating component helper instance of type alma.scheduling.master_scheduler.MSHelper
SCHEDULING: The MasterScheduler has been constructed.
Jun 4, 2004 11:38:19 AM alma.acs.container.ComponentHelper getComponentImpl
FINER: component implementation class 'alma.scheduling.master_scheduler.MasterScheduler' instantiated.
Jun 4, 2004 11:38:19 AM alma.acs.container.AcsContainer activate_component
FINER: component MASTER_SCHEDULER implements operations interface directly; no dynamic translator proxy used.
[ POA ComponentPOAMASTER_SCHEDULER - ready ]
Jun 4, 2004 11:38:19 AM alma.acs.container.ComponentAdapter activateComponent
FINER: entering ComponentAdapter#activateComponent for MASTER_SCHEDULER
Jun 4, 2004 11:38:19 AM alma.acs.container.corba.AcsCorba activateComponent
FINER: entering activateComponent: name=MASTER_SCHEDULER
Jun 4, 2004 11:38:19 AM alma.acs.container.corba.AcsCorba activateComponent
FINER: component 'MASTER_SCHEDULER' activated as CORBA object.
Jun 4, 2004 11:38:19 AM alma.acs.container.ComponentAdapter _getInterfaces
FINE: interfaces of component 'MASTER_SCHEDULER': IDL:alma/scheduling/MS:1.0IDL:alma/ACS/ACSComponent:1.0IDL:alma/scheduling/Executive_to_Scheduling:1.0IDL:alma/scheduling/TelescopeOperator_to_Scheduling:1.0IDL:omg.org/CORBA/Object:1.0
Jun 4, 2004 11:38:19 AM alma.acs.container.AcsContainer activate_component
FINE: about to initialize component MASTER_SCHEDULER
Jun 4, 2004 11:38:19 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component MASTER_SCHEDULER from NEW to INITIALIZING
Jun 4, 2004 11:38:19 AM alma.acs.logging.ClientLogManager getLoggerForComponent
FINE: a logger for component .MASTER_SCHEDULER is available.
Jun 4, 2004 11:38:19 AM alma.scheduling.master_scheduler.SchedulingPublisher <init>
INFO: SCHEDULING: Scheduling Publisher has been created
Attempting to create the SCHEDULING_CHANNEL channel.
Jun 4, 2004 11:38:19 AM alma.acs.logging.ClientLogManager getLoggerForComponent
FINE: a logger for component .MASTER_SCHEDULER is available.
SCHEDULING: Message queue created
Jun 4, 2004 11:38:19 AM alma.acs.logging.ClientLogManager getLoggerForComponent
FINE: a logger for component .MASTER_SCHEDULER is available.
Jun 4, 2004 11:38:19 AM alma.acs.container.ContainerServicesImpl getComponent
FINE: will retrieve remote component 'ARCHIVE_CONNECTION' using ACS Manager#get_component
Jun 4, 2004 11:38:19 AM alma.acs.container.ContainerServicesImpl getComponent
FINE: component ARCHIVE_CONNECTION retrieved successfully.
Jun 4, 2004 11:38:19 AM alma.acs.container.ContainerSealant invoke
INFO: intercepted a call to 'ARCHIVE_CONNECTION#getOperational'...
Jun 4, 2004 11:38:19 AM alma.archive.components.OperationalImpl connect
INFO: Connected to the internal interface
Jun 4, 2004 11:38:19 AM alma.archive.components.OperationalImpl connect
INFO: Connected to the Liatener Manager
Jun 4, 2004 11:38:19 AM alma.acs.container.corba.AcsCorba activateOffShoot
FINER: offshoot of type 'alma.archive.components.OperationalImpl' activated as a CORBA object.
Jun 4, 2004 11:38:19 AM alma.acs.container.ContainerSealant invoke
INFO: returning from ARCHIVE_CONNECTION#getOperational after 6 ms.
Jun 4, 2004 11:38:19 AM alma.acs.logging.ClientLogManager getLoggerForComponent
FINE: a logger for component .MASTER_SCHEDULER is available.
Jun 4, 2004 11:38:19 AM alma.acs.logging.ClientLogManager getLoggerForComponent
FINE: a logger for component .MASTER_SCHEDULER is available.
Jun 4, 2004 11:38:19 AM alma.scheduling.master_scheduler.ALMAArchive <init>
FINE: SCHEDULING: The ALMAArchive has been constructed.
Jun 4, 2004 11:38:19 AM alma.acs.logging.ClientLogManager getLoggerForComponent
FINE: a logger for component .MASTER_SCHEDULER is available.
Jun 4, 2004 11:38:19 AM alma.acs.logging.ClientLogManager getLoggerForComponent
FINE: a logger for component .MASTER_SCHEDULER is available.
Jun 4, 2004 11:38:19 AM alma.scheduling.master_scheduler.ALMATelescopeOperator <init>
INFO: SCHEDULING: The ALMATelescopeOperator has been constructed.
SCHEDULING: The PIProxy has been constructed.
Jun 4, 2004 11:38:19 AM alma.acs.logging.ClientLogManager getLoggerForComponent
FINE: a logger for component .MASTER_SCHEDULER is available.
Jun 4, 2004 11:38:19 AM alma.scheduling.master_scheduler.ALMADispatcher <init>
INFO: SCHEDULING: The Dispatcher has been constructed.
Jun 4, 2004 11:38:19 AM alma.acs.logging.ClientLogManager getLoggerForComponent
FINE: a logger for component .MASTER_SCHEDULER is available.
Jun 4, 2004 11:38:19 AM alma.acs.logging.ClientLogManager getLoggerForComponent
FINE: a logger for component .MASTER_SCHEDULER is available.
Jun 4, 2004 11:38:19 AM alma.scheduling.master_scheduler.SchedulingPublisher <init>
INFO: SCHEDULING: Scheduling Publisher has been created
SCHEDULING: The PipelineProxy has been constructed.
Jun 4, 2004 11:38:19 AM alma.acs.logging.ClientLogManager getLoggerForComponent
FINE: a logger for component .MASTER_SCHEDULER is available.
Jun 4, 2004 11:38:19 AM alma.acs.logging.ClientLogManager getLoggerForComponent
FINE: a logger for component .MASTER_SCHEDULER is available.
#########ControlEventReceiver created######
Jun 4, 2004 11:38:19 AM alma.acs.logging.ClientLogManager getLoggerForComponent
FINE: a logger for component .MASTER_SCHEDULER is available.
Jun 4, 2004 11:38:19 AM alma.scheduling.receivers.PipelineEventReceiver <init>
INFO: SCHEDULING: PipelineEventListener created.
Jun 4, 2004 11:38:19 AM alma.acs.logging.ClientLogManager getLoggerForComponent
FINE: a logger for component .MASTER_SCHEDULER is available.
Jun 4, 2004 11:38:19 AM alma.acs.logging.ClientLogManager getLoggerForComponent
FINE: a logger for component .MASTER_SCHEDULER is available.
Jun 4, 2004 11:38:19 AM alma.scheduling.project_manager.ProjectManager <init>
INFO: SCHEDULING: Trying to get NCs
Suppler.subscription_change(...) the 'CONTROLSYSTEM' channel: override to do something useful.
Jun 4, 2004 11:38:19 AM alma.scheduling.project_manager.ProjectManager <init>
INFO: SCHEDULING: Subscribed to CONTROL
Suppler.subscription_change(...) the 'sciencepipeline' channel: override to do something useful.
Jun 4, 2004 11:38:19 AM alma.scheduling.project_manager.ProjectManager <init>
INFO: SCHEDULING: Subscribed to PIPELINE
Suppler.subscription_change(...) the 'TelCalPublisherEventNC' channel: override to do something useful.
Suppler.subscription_change(...) the 'TelCalPublisherEventNC' channel: override to do something useful.
Suppler.subscription_change(...) the 'TelCalPublisherEventNC' channel: override to do something useful.
Suppler.subscription_change(...) the 'TelCalPublisherEventNC' channel: override to do something useful.
Jun 4, 2004 11:38:19 AM alma.scheduling.project_manager.ProjectManager <init>
INFO: SCHEDULING: Subscribed to TELCAL PointingReducedEvent
Suppler.subscription_change(...) the 'TelCalPublisherEventNC' channel: override to do something useful.
Suppler.subscription_change(...) the 'TelCalPublisherEventNC' channel: override to do something useful.
Suppler.subscription_change(...) the 'TelCalPublisherEventNC' channel: override to do something useful.
Suppler.subscription_change(...) the 'TelCalPublisherEventNC' channel: override to do something useful.
Jun 4, 2004 11:38:19 AM alma.scheduling.project_manager.ProjectManager <init>
INFO: SCHEDULING: Subscribed to TELCAL FocusReducedEvent
Jun 4, 2004 11:38:19 AM alma.scheduling.project_manager.ProjectManager <init>
INFO: SCHEDULING: Got NCs
SCHEDULING: The Clock has been constructed.
Jun 4, 2004 11:38:19 AM alma.acs.logging.ClientLogManager getLoggerForComponent
FINE: a logger for component .MASTER_SCHEDULER is available.
Jun 4, 2004 11:38:19 AM alma.scheduling.master_scheduler.MasterScheduler initialize
INFO: SCHEDULING: The MasterScheduler has been initialized.
Jun 4, 2004 11:38:19 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component MASTER_SCHEDULER from INITIALIZING to INITIALIZED
Jun 4, 2004 11:38:19 AM alma.scheduling.master_scheduler.MasterScheduler execute
INFO: SCHEDULING: starting execute
Jun 4, 2004 11:38:19 AM alma.archive.components.OperationalImpl query
FINE: Querying XPath: /sbl:SchedBlock Schema: SchedBlock
Jun 4, 2004 11:38:19 AM alma.archive.components.OperationalImpl query
FINEST: Schema location for SchedBlock is uid://X0000000000000064/X0000000f
Jun 4, 2004 11:38:20 AM alma.archive.database.helpers.DBConfiguration searchConfigFile
WARNING: Property archive.config.file not set, using default config file: /alma/ACS-3.0/acsdata/config/dbConfig.xml
Jun 4, 2004 11:38:20 AM alma.archive.database.helpers.DBConfiguration readConfig
INFO: Setting database configuration.
Jun 4, 2004 11:38:20 AM alma.archive.database.helpers.DBConfiguration readConfig
INFO: Using database backend xindice
Jun 4, 2004 11:38:20 AM alma.archive.components.OperationalImpl query
FINE: Returning cursor to user
Jun 4, 2004 11:38:20 AM alma.acs.container.corba.AcsCorba activateOffShoot
FINER: offshoot of type 'alma.archive.components.CursorImpl' activated as a CORBA object.
Jun 4, 2004 11:38:21 AM alma.scheduling.master_scheduler.MasterScheduler pollArchive
INFO: SCHEDULING: Getting SBs from archive. There are 4
Jun 4, 2004 11:38:21 AM alma.scheduling.master_scheduler.MasterScheduler pollArchive
INFO: SCHEDULING: sbs not null storing into sbQueue
Jun 4, 2004 11:38:21 AM alma.scheduling.master_scheduler.MasterScheduler run
INFO: MSsleeping!
Jun 4, 2004 11:38:21 AM alma.scheduling.master_scheduler.ALMATelescopeOperator setMessageQueue
INFO: SCHEDULING: Message queue set in operator
Jun 4, 2004 11:38:21 AM alma.scheduling.master_scheduler.MasterScheduler execute
INFO: SCHEDULING: The MasterScheduler is executing.
Jun 4, 2004 11:38:21 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component MASTER_SCHEDULER from INITIALIZED to OPERATIONAL
Jun 4, 2004 11:38:21 AM alma.acs.container.AcsContainer activate_component
INFO: component MASTER_SCHEDULER activated and initialized in 1632 ms.
Jun 4, 2004 11:38:21 AM alma.acs.container.AcsContainer activate_component
INFO: >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
Jun 4, 2004 11:38:23 AM alma.acs.container.AcsContainer ping
FINER: ping received.
Jun 4, 2004 11:38:25 AM alma.acs.container.ContainerSealant invoke
INFO: intercepted a call to 'MASTER_SCHEDULER#startScheduling'...
Jun 4, 2004 11:38:25 AM alma.scheduling.master_scheduler.MasterScheduler startScheduler
INFO: SCHEDULING: MODE == dynamic
Jun 4, 2004 11:38:25 AM alma.acs.logging.ClientLogManager getLoggerForComponent
FINE: a logger for component .MASTER_SCHEDULER is available.
Jun 4, 2004 11:38:25 AM alma.scheduling.master_scheduler.MasterScheduler startScheduler
INFO: SCHEDULING: New scheduler started.
Jun 4, 2004 11:38:25 AM alma.scheduling.master_scheduler.MasterScheduler startScheduler
INFO: SCHEDULING: Start of new project.
SCHEDULING: SchedulerEventReceiver created.
Jun 4, 2004 11:38:25 AM alma.acs.container.ContainerSealant invoke
INFO: returning from MASTER_SCHEDULER#startScheduling after 21 ms.
Jun 4, 2004 11:38:25 AM alma.scheduling.scheduler.Scheduler run
INFO: SCHEDULING: Scheduler is running in dynamic mode!
SCHEDULING SUB QUEUE size = 4
Jun 4, 2004 11:38:25 AM alma.acs.container.archive.ArchiveProxy getUid
FINE: trying to access the identifier archive...
Jun 4, 2004 11:38:25 AM alma.acs.container.archive.ArchiveProxy getUid
FINE: about to call getIdNamespace()...
Jun 4, 2004 11:38:25 AM alma.acs.container.ContainerSealant invoke
INFO: intercepted a call to 'IDENTIFIER_ARCHIVE#getIdNamespace'...
Jun 4, 2004 11:38:25 AM alma.acs.container.ContainerSealant invoke
INFO: returning from IDENTIFIER_ARCHIVE#getIdNamespace after 8 ms.
Jun 4, 2004 11:38:25 AM alma.acs.container.archive.ArchiveProxy getUid
FINE: getIdNamespace() returned uid://X0000000000000067/X00000000
Jun 4, 2004 11:38:25 AM alma.acs.container.ContainerSealant invoke
INFO: intercepted a call to 'IDENTIFIER_ARCHIVE#name'...
Jun 4, 2004 11:38:25 AM alma.acs.container.ContainerSealant invoke
INFO: returning from IDENTIFIER_ARCHIVE#name after 19 ms.
Jun 4, 2004 11:38:25 AM alma.acs.container.ContainerServicesImpl releaseComponent
FINE: about to release component IDENTIFIER_ARCHIVE
Jun 4, 2004 11:38:25 AM alma.acs.container.AcsManagerProxy release_component
FINER: released 'IDENTIFIER_ARCHIVE' with the manager. Remaining clients: 1
Jun 4, 2004 11:38:25 AM alma.acs.container.ContainerServicesImpl releaseComponent
INFO: client 'MASTER_SCHEDULER' has successfully released a component with curl=IDENTIFIER_ARCHIVE
Jun 4, 2004 11:38:25 AM alma.scheduling.master_scheduler.ALMATelescopeOperator sortSBs
INFO: SCHEDULING: sorting SBs!
Jun 4, 2004 11:38:25 AM alma.archive.components.OperationalImpl query
FINE: Querying XPath: /sbl:SchedBlock Schema: SchedBlock
Jun 4, 2004 11:38:25 AM alma.archive.components.OperationalImpl query
FINEST: Schema location for SchedBlock is uid://X0000000000000064/X0000000f
Jun 4, 2004 11:38:26 AM alma.archive.components.OperationalImpl query
FINE: Returning cursor to user
Jun 4, 2004 11:38:26 AM alma.acs.container.corba.AcsCorba activateOffShoot
FINER: offshoot of type 'alma.archive.components.CursorImpl' activated as a CORBA object.
Jun 4, 2004 11:38:26 AM alma.scheduling.master_scheduler.ALMATelescopeOperator isCompletedSb
INFO: SCHEDULING: checking for non-complete SB
Jun 4, 2004 11:38:26 AM alma.archive.components.OperationalImpl query
FINE: Querying XPath: /sbl:SchedBlock Schema: SchedBlock
Jun 4, 2004 11:38:26 AM alma.archive.components.OperationalImpl query
FINEST: Schema location for SchedBlock is uid://X0000000000000064/X0000000f
Jun 4, 2004 11:38:27 AM alma.archive.components.OperationalImpl query
FINE: Returning cursor to user
Jun 4, 2004 11:38:27 AM alma.acs.container.corba.AcsCorba activateOffShoot
FINER: offshoot of type 'alma.archive.components.CursorImpl' activated as a CORBA object.
Jun 4, 2004 11:38:27 AM alma.scheduling.master_scheduler.ALMATelescopeOperator isCompletedSb
INFO: SCHEDULING: checking for non-complete SB
Jun 4, 2004 11:38:27 AM alma.scheduling.master_scheduler.ALMATelescopeOperator selectSB
INFO: SCHEDULING: in TO. reply =uid://X0000000000000066/X00000002
Jun 4, 2004 11:38:27 AM alma.acs.container.ContainerServicesImpl getComponent
FINE: will retrieve remote component 'MASTER_SCHEDULER' using ACS Manager#get_component
Jun 4, 2004 11:38:27 AM alma.acs.container.ContainerServicesImpl getComponent
FINE: component MASTER_SCHEDULER retrieved successfully.
Jun 4, 2004 11:38:27 AM alma.scheduling.master_scheduler.ALMATelescopeOperator selectSB
INFO: SCHEDULING: in TO. ms response about to be called
Jun 4, 2004 11:38:27 AM alma.acs.container.ContainerSealant invoke
INFO: intercepted a call to 'MASTER_SCHEDULER#response'...
Jun 4, 2004 11:38:27 AM alma.scheduling.master_scheduler.MasterScheduler response
INFO: SCHEDULING: in MS. MessageID = uid://X0000000000000067/X00000000
Jun 4, 2004 11:38:27 AM alma.scheduling.master_scheduler.MasterScheduler response
INFO: SCHEDULING: in MS. Reply (sb id) = uid://X0000000000000066/X00000002
Jun 4, 2004 11:38:27 AM alma.scheduling.master_scheduler.MasterScheduler response
INFO: SCHEDULING: in MS. messageQueue size = 1
Jun 4, 2004 11:38:27 AM alma.scheduling.master_scheduler.MasterScheduler response
INFO: SCHEDULING: in MS. Got message with id=uid://X0000000000000067/X00000000
Jun 4, 2004 11:38:27 AM alma.scheduling.master_scheduler.MasterScheduler response
INFO: SCHEDULING: in MS. message = uid://X0000000000000067/X00000000 gotten and reply = uid://X0000000000000066/X00000002 sent.
Jun 4, 2004 11:38:27 AM alma.acs.container.ContainerSealant invoke
INFO: returning from MASTER_SCHEDULER#response after 29 ms.
Jun 4, 2004 11:38:27 AM alma.scheduling.master_scheduler.ALMATelescopeOperator selectSB
INFO: SCHEDULING: in TO. ms response called
Jun 4, 2004 11:38:27 AM alma.scheduling.master_scheduler.ALMATelescopeOperator selectSB
INFO: SCHEDULING: in TO. timer.join()
Jun 4, 2004 11:38:27 AM alma.scheduling.scheduler.Scheduler run
INFO: SCHEDULING: in scheduler. selectedSB = uid://X0000000000000066/X00000002
Jun 4, 2004 11:38:27 AM alma.scheduling.scheduler.Scheduler run
INFO: SCHEDULING: Message uid://X0000000000000067/X00000000 removed from queue.
Jun 4, 2004 11:38:27 AM alma.scheduling.master_scheduler.ALMADispatcher sendToControl
INFO: SCHEDULING: Sending SB with id = uid://X0000000000000066/X00000002 to controller.
Jun 4, 2004 11:38:27 AM alma.acs.container.ContainerServicesImpl getComponent
FINE: will retrieve remote component 'ControlSystem1' using ACS Manager#get_component
Jun 4, 2004 11:38:27 AM alma.acs.container.ContainerServicesImpl getComponent
FINE: component ControlSystem1 retrieved successfully.
Jun 4, 2004 11:38:27 AM alma.scheduling.master_scheduler.ALMADispatcher sendToControl
INFO: SCHEDULING: Got control system
Jun 4, 2004 11:38:27 AM alma.acs.container.ContainerSealant invoke
INFO: intercepted a call to 'ControlSystem1#createSubArray'...
Jun 4, 2004 11:38:27 AM alma.Control.masterController.ControlSystemImpl createSubArray
INFO: createSubArray() called
Jun 4, 2004 11:38:27 AM alma.acs.container.ContainerServicesImpl getDynamicComponent
FINE: getDynamicComponent called with compName=ArrayController0 compType=IDL:alma/Control/ArrayController:1.0 compCode=* compContainer=* markAsDefault=false
Jun 4, 2004 11:38:27 AM alma.acs.container.AcsContainer activate_component
INFO: <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<
Jun 4, 2004 11:38:27 AM alma.acs.container.AcsContainer activate_component
INFO: activate_component: handle=16777230 name=ArrayController0 helperClass=alma.Control.arrayInterfaces.ArrayControllerHelper type=IDL:alma/Control/ArrayController:1.0
Jun 4, 2004 11:38:27 AM alma.acs.container.AcsContainer createComponentHelper
FINER: creating component helper instance of type alma.Control.arrayInterfaces.ArrayControllerHelper
Jun 4, 2004 11:38:27 AM alma.acs.container.ComponentHelper getComponentImpl
FINER: component implementation class 'alma.Control.arrayInterfaces.ArrayControllerImpl' instantiated.
Jun 4, 2004 11:38:27 AM alma.acs.container.AcsContainer activate_component
FINER: component ArrayController0 implements operations interface directly; no dynamic translator proxy used.
[ POA ComponentPOAArrayController0 - ready ]
Jun 4, 2004 11:38:27 AM alma.acs.container.ComponentAdapter activateComponent
FINER: entering ComponentAdapter#activateComponent for ArrayController0
Jun 4, 2004 11:38:27 AM alma.acs.container.corba.AcsCorba activateComponent
FINER: entering activateComponent: name=ArrayController0
Jun 4, 2004 11:38:27 AM alma.acs.container.corba.AcsCorba activateComponent
FINER: component 'ArrayController0' activated as CORBA object.
Jun 4, 2004 11:38:27 AM alma.acs.container.ComponentAdapter _getInterfaces
FINE: interfaces of component 'ArrayController0': IDL:alma/Control/ArrayController:1.0IDL:alma/ACS/ACSComponent:1.0IDL:omg.org/CORBA/Object:1.0IDL:alma/Control/ArrayMonitor:1.0
Jun 4, 2004 11:38:27 AM alma.acs.container.AcsContainer activate_component
FINE: about to initialize component ArrayController0
Jun 4, 2004 11:38:27 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component ArrayController0 from NEW to INITIALIZING
Jun 4, 2004 11:38:27 AM alma.acs.logging.ClientLogManager getLoggerForComponent
FINE: a logger for component .ArrayController0 is available.
Jun 4, 2004 11:38:27 AM alma.Control.arrayInterfaces.ArrayMonitorImpl initialize
INFO: CONTROL: ArrayMonitorImpl.initialize() called...
Jun 4, 2004 11:38:27 AM alma.acs.container.ContainerServicesImpl getComponent
FINE: will retrieve remote component 'ARCHIVE_CONNECTION' using ACS Manager#get_component
Jun 4, 2004 11:38:27 AM alma.acs.container.ContainerServicesImpl getComponent
FINE: component ARCHIVE_CONNECTION retrieved successfully.
Jun 4, 2004 11:38:27 AM alma.acs.container.ContainerSealant invoke
INFO: intercepted a call to 'ARCHIVE_CONNECTION#getOperational'...
Jun 4, 2004 11:38:27 AM alma.archive.components.OperationalImpl connect
INFO: Connected to the internal interface
Jun 4, 2004 11:38:27 AM alma.archive.components.OperationalImpl connect
INFO: Connected to the Liatener Manager
Jun 4, 2004 11:38:27 AM alma.acs.container.corba.AcsCorba activateOffShoot
FINER: offshoot of type 'alma.archive.components.OperationalImpl' activated as a CORBA object.
Jun 4, 2004 11:38:27 AM alma.acs.container.ContainerSealant invoke
INFO: returning from ARCHIVE_CONNECTION#getOperational after 3 ms.
Jun 4, 2004 11:38:27 AM alma.acs.logging.ClientLogManager getLoggerForComponent
FINE: a logger for component .ArrayController0 is available.
Jun 4, 2004 11:38:27 AM alma.acs.logging.ClientLogManager getLoggerForComponent
FINE: a logger for component .ArrayController0 is available.
Jun 4, 2004 11:38:27 AM alma.Control.arrayInterfaces.ArrayControllerImpl initialize
INFO: CONTROL: ArrayControllerImpl.initialize() called...
Suppler.subscription_change(...) the 'CONTROLSYSTEM' channel: override to do something useful.
Suppler.subscription_change(...) the 'CONTROLSYSTEM' channel: override to do something useful.
Suppler.subscription_change(...) the 'CONTROLSYSTEM' channel: override to do something useful.
Jun 4, 2004 11:38:27 AM alma.acs.container.ContainerServicesImpl getDynamicComponent
FINE: getDynamicComponent called with compName=DelayServer compType=IDL:alma/Control/DelayServer:1.0 compCode=* compContainer=* markAsDefault=false
Jun 4, 2004 11:38:28 AM alma.acs.container.AcsContainer activate_component
INFO: <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<
Jun 4, 2004 11:38:28 AM alma.acs.container.AcsContainer activate_component
INFO: activate_component: handle=16777231 name=DelayServer helperClass=alma.Control.DelayServerImpl.DelayServerHelper type=IDL:alma/Control/DelayServer:1.0
Jun 4, 2004 11:38:28 AM alma.acs.container.AcsContainer createComponentHelper
FINER: creating component helper instance of type alma.Control.DelayServerImpl.DelayServerHelper
Jun 4, 2004 11:38:28 AM alma.acs.container.ComponentHelper getComponentImpl
FINER: component implementation class 'alma.Control.DelayServerImpl.DelayServerImpl' instantiated.
Jun 4, 2004 11:38:28 AM alma.acs.container.AcsContainer activate_component
FINER: component DelayServer implements operations interface directly; no dynamic translator proxy used.
[ POA ComponentPOADelayServer - ready ]
Jun 4, 2004 11:38:28 AM alma.acs.container.ComponentAdapter activateComponent
FINER: entering ComponentAdapter#activateComponent for DelayServer
Jun 4, 2004 11:38:28 AM alma.acs.container.corba.AcsCorba activateComponent
FINER: entering activateComponent: name=DelayServer
Jun 4, 2004 11:38:28 AM alma.acs.container.corba.AcsCorba activateComponent
FINER: component 'DelayServer' activated as CORBA object.
Jun 4, 2004 11:38:28 AM alma.acs.container.ComponentAdapter _getInterfaces
FINE: interfaces of component 'DelayServer': IDL:alma/Control/DelayServer:1.0IDL:alma/ACS/ACSComponent:1.0IDL:omg.org/CORBA/Object:1.0
Jun 4, 2004 11:38:28 AM alma.acs.container.AcsContainer activate_component
FINE: about to initialize component DelayServer
Jun 4, 2004 11:38:28 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component DelayServer from NEW to INITIALIZING
Jun 4, 2004 11:38:28 AM alma.acs.logging.ClientLogManager getLoggerForComponent
FINE: a logger for component .DelayServer is available.
freeTime: 1.0
Suppler.subscription_change(...) the 'CONTROLSYSTEM' channel: override to do something useful.
Jun 4, 2004 11:38:28 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component DelayServer from INITIALIZING to INITIALIZED
Jun 4, 2004 11:38:28 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component DelayServer from INITIALIZED to OPERATIONAL
Jun 4, 2004 11:38:28 AM alma.acs.container.AcsContainer activate_component
INFO: component DelayServer activated and initialized in 140 ms.
Jun 4, 2004 11:38:28 AM alma.acs.container.AcsContainer activate_component
INFO: >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
Jun 4, 2004 11:38:28 AM alma.acs.container.ContainerSealant invoke
INFO: intercepted a call to 'DelayServer#calcInit'...
Jun 4, 2004 11:38:28 AM alma.acs.container.ContainerSealant invoke
INFO: returning from DelayServer#calcInit after 11 ms.
Jun 4, 2004 11:38:28 AM alma.acs.container.ContainerSealant invoke
INFO: intercepted a call to 'DelayServer#addAntenna'...
Jun 4, 2004 11:38:28 AM alma.acs.container.ContainerSealant invoke
INFO: returning from DelayServer#addAntenna after 0 ms.
Jun 4, 2004 11:38:28 AM alma.acs.container.ContainerSealant invoke
INFO: intercepted a call to 'DelayServer#addSource'...
Jun 4, 2004 11:38:28 AM alma.acs.container.ContainerSealant invoke
INFO: returning from DelayServer#addSource after 0 ms.
Jun 4, 2004 11:38:28 AM alma.acs.container.ContainerSealant invoke
INFO: intercepted a call to 'DelayServer#setPressure'...
Jun 4, 2004 11:38:28 AM alma.acs.container.ContainerSealant invoke
INFO: returning from DelayServer#setPressure after 0 ms.
Jun 4, 2004 11:38:28 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component ArrayController0 from INITIALIZING to INITIALIZED
Jun 4, 2004 11:38:28 AM alma.Control.arrayInterfaces.ArrayMonitorImpl execute
INFO: eArrayMonitorImpl.xecute() called...
Jun 4, 2004 11:38:28 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component ArrayController0 from INITIALIZED to OPERATIONAL
Jun 4, 2004 11:38:28 AM alma.acs.container.AcsContainer activate_component
INFO: component ArrayController0 activated and initialized in 733 ms.
Jun 4, 2004 11:38:28 AM alma.acs.container.AcsContainer activate_component
INFO: >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
Jun 4, 2004 11:38:28 AM alma.acs.container.ContainerSealant invoke
INFO: returning from ControlSystem1#createSubArray after 1243 ms.
Jun 4, 2004 11:38:28 AM alma.scheduling.master_scheduler.ALMADispatcher sendToControl
INFO: SCHEDULING: Got array controller
Jun 4, 2004 11:38:28 AM alma.acs.container.ContainerSealant invoke
INFO: intercepted a call to 'ArrayController0#observeNow'...
Jun 4, 2004 11:38:28 AM alma.Control.arrayInterfaces.ArrayControllerImpl observe
INFO: observe() called...
Jun 4, 2004 11:38:28 AM alma.archive.components.OperationalImpl retrieve
FINEST: Getting URI: uid://X0000000000000066/X00000002
Jun 4, 2004 11:38:28 AM alma.archive.components.OperationalImpl retrieve
FINEST: Fetching Xml: uid://X0000000000000066/X00000002
Jun 4, 2004 11:38:28 AM alma.archive.components.OperationalImpl retrieve
FINEST: Fetching Status: uid://X0000000000000066/X00000002
Jun 4, 2004 11:38:28 AM alma.archive.components.OperationalImpl retrieve
FINEST: Building Result: uid://X0000000000000066/X00000002
Jun 4, 2004 11:38:28 AM alma.archive.components.OperationalImpl retrieve
FINEST: Schema: uid://X0000000000000064/X0000000f
Jun 4, 2004 11:38:28 AM alma.archive.components.OperationalImpl retrieve
FINEST: Type name: SchedBlock
Jun 4, 2004 11:38:28 AM alma.archive.components.OperationalImpl retrieve
FINEST: Version: 0
Jun 4, 2004 11:38:28 AM alma.archive.components.OperationalImpl retrieve
FINEST: Time Stamp: 2004-06-04T11:33:41.871
Jun 4, 2004 11:38:28 AM alma.acs.container.ContainerServicesImpl getComponent
FINE: will retrieve remote component 'OBSERVATION_CONTROL_SIM' using ACS Manager#get_component
Jun 4, 2004 11:38:28 AM alma.acs.container.ContainerServicesImpl getComponent
FINE: component OBSERVATION_CONTROL_SIM retrieved successfully.
[ Connected to 134.171.12.100:3052 ]
Jun 4, 2004 11:38:29 AM alma.archive.components.OperationalImpl store
FINEST: Storing URI: uid://X0000000000000067/X00000001 Type: ExecBlock
Jun 4, 2004 11:38:29 AM alma.archive.components.OperationalImpl store
FINEST: Schema Location for Type ExecBlock is uid://X0000000000000064/X00000010
Jun 4, 2004 11:38:29 AM alma.archive.components.OperationalImpl store
FINEST: Stored: uid://X0000000000000067/X00000001 successfully
Jun 4, 2004 11:38:29 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38812,localport=38798]
Jun 4, 2004 11:38:30 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38813,localport=38798]
Jun 4, 2004 11:38:31 AM alma.Control.arrayInterfaces.ArrayControllerImpl sendExecBlockEvent
INFO: CONTROL: spedito ExecBlockEvent
Jun 4, 2004 11:38:31 AM alma.scheduling.receivers.ControlEventReceiver push_structured_event
INFO: SCHEDULING: Got event from control
Jun 4, 2004 11:38:31 AM alma.scheduling.receivers.ControlEventReceiver push_structured_event
INFO: SCHEDULING: Event reason = started
Jun 4, 2004 11:38:31 AM alma.scheduling.receivers.ControlEventReceiver push_structured_event
INFO: SCHEDULING: Received sb start event from control.
SCHEDULING: Control event received in scheduler receiver!
SCHEDULING: Got event from Control (scheduler receiver)
SCHEDULING: process sched event started
SCHEDULING: in scheduler, id=uid://X0000000000000066/X00000002
SCHEDULING: Sb ids match!
SCHEDULING: id is in queue.. do something
Jun 4, 2004 11:38:31 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38814,localport=38798]
Jun 4, 2004 11:38:32 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38817,localport=38798]
Jun 4, 2004 11:38:33 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38818,localport=38798]
Jun 4, 2004 11:38:34 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38819,localport=38798]
Jun 4, 2004 11:38:35 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38820,localport=38798]
Jun 4, 2004 11:38:36 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38821,localport=38798]
Jun 4, 2004 11:38:37 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38822,localport=38798]
Jun 4, 2004 11:38:38 AM alma.acs.container.ContainerSealant invoke
INFO: intercepted a call to 'GETTELCALRESULTS1#publishPointingResult'...
Jun 4, 2004 11:38:38 AM alma.acs.container.ContainerSealant invoke
INFO: intercepted a call to 'GETTELCALRESULTS1#publishPointingResult'...
Jun 4, 2004 11:38:38 AM alma.TelCalPublisher.GetTelCalResultsImpl.GetTelCalResultsImpl publishPointingResult
INFO: publishPointingResult...
Jun 4, 2004 11:38:38 AM alma.TelCalPublisher.GetTelCalResultsImpl.GetTelCalResultsImpl publishPointingResult
INFO: publishPointingResult...
PointingResultConverter...
PointingResultConverter...
Jun 4, 2004 11:38:38 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38823,localport=38798]
Jun 4, 2004 11:38:38 AM alma.TelCalPublisher.GetTelCalResultsImpl.GetTelCalResultsImpl storeResultInList
INFO: list size=2
Jun 4, 2004 11:38:38 AM alma.TelCalPublisher.GetTelCalResultsImpl.GetTelCalResultsImpl storeResultInList
INFO: list size=2
Jun 4, 2004 11:38:38 AM alma.TelCalPublisher.GetTelCalResultsImpl.GetTelCalResultsImpl sendEvent
INFO: sendEvent
pointing event
Jun 4, 2004 11:38:38 AM alma.TelCalPublisher.GetTelCalResultsImpl.GetTelCalResultsImpl sendEvent
INFO: sendEvent
pointing event
pointing event: end
pointing event: end
SCHEDULING: Got PointingReduced event
SCHEDULING: Starting to process the PointingReduced event
SCHEDULING: Got PointingReduced event
SCHEDULING: Starting to process the PointingReduced event
Jun 4, 2004 11:38:38 AM alma.archive.components.OperationalImpl store
FINEST: Storing URI: uid://X0000000000000067/X00000003 Type: PointingResult
Jun 4, 2004 11:38:38 AM alma.archive.components.OperationalImpl store
FINEST: Schema Location for Type PointingResult is uid://X0000000000000064/X00000014
SCHEDULING: Control event received in scheduler receiver!
Jun 4, 2004 11:38:39 AM alma.scheduling.receivers.ControlEventReceiver push_structured_event
INFO: SCHEDULING: Got event from control
Jun 4, 2004 11:38:39 AM alma.Control.arrayInterfaces.ArrayControllerImpl sendExecBlockEvent
INFO: CONTROL: spedito ExecBlockEvent
Jun 4, 2004 11:38:39 AM alma.acs.container.ContainerSealant invoke
INFO: returning from ArrayController0#observeNow after 10572 ms.
SCHEDULING: Got event from Control (scheduler receiver)
SCHEDULING: process sched event started
SCHEDULING: in scheduler, id=uid://X0000000000000066/X00000002
SCHEDULING: Sb ids match!
SCHEDULING: id is in queue.. do something
Jun 4, 2004 11:38:39 AM alma.archive.components.OperationalImpl store
FINEST: Storing URI: uid://X0000000000000067/X00000002 Type: PointingResult
Jun 4, 2004 11:38:39 AM alma.archive.components.OperationalImpl store
FINEST: Schema Location for Type PointingResult is uid://X0000000000000064/X00000014
Jun 4, 2004 11:38:39 AM alma.scheduling.receivers.ControlEventReceiver push_structured_event
INFO: SCHEDULING: Event reason = end
Jun 4, 2004 11:38:39 AM alma.scheduling.receivers.ControlEventReceiver push_structured_event
INFO: SCHEDULING: Received sb end event from control.
Jun 4, 2004 11:38:39 AM alma.scheduling.receivers.ControlEventReceiver receive
INFO: SCHEDULING: Starting to process the control event
Jun 4, 2004 11:38:39 AM alma.scheduling.receivers.ControlEventReceiver receive
INFO: SCHEDULING: Event reason = end
Jun 4, 2004 11:38:39 AM alma.scheduling.receivers.ControlEventReceiver receive
INFO: SCHEDULING: Received sb end event from control.
Jun 4, 2004 11:38:39 AM alma.archive.components.OperationalImpl store
FINEST: Stored: uid://X0000000000000067/X00000003 successfully
xmlEntity= alma.xmlentity.XmlEntityStruct@15dc37d
SCHEDULING: process control event started
Jun 4, 2004 11:38:39 AM alma.scheduling.master_scheduler.ALMAArchive updateSchedBlock
INFO: SCHEDULING: sb id=uid://X0000000000000066/X00000002
Jun 4, 2004 11:38:39 AM alma.scheduling.master_scheduler.ALMAArchive updateSchedBlock
INFO: SCHEDULING: ** This is where the sb will be linked to its execblock **
Jun 4, 2004 11:38:39 AM alma.archive.components.OperationalImpl retrieve
FINEST: Getting URI: uid://X0000000000000066/X00000002
Jun 4, 2004 11:38:39 AM alma.archive.components.OperationalImpl retrieve
FINEST: Fetching Xml: uid://X0000000000000066/X00000002
Jun 4, 2004 11:38:39 AM alma.acs.container.ContainerSealant invoke
INFO: returning from GETTELCALRESULTS1#publishPointingResult after 989 ms.
Jun 4, 2004 11:38:39 AM alma.archive.components.OperationalImpl store
FINEST: Stored: uid://X0000000000000067/X00000002 successfully
xmlEntity= alma.xmlentity.XmlEntityStruct@8ae59a
Jun 4, 2004 11:38:39 AM alma.acs.container.ContainerSealant invoke
INFO: returning from GETTELCALRESULTS1#publishPointingResult after 1004 ms.
Jun 4, 2004 11:38:39 AM alma.archive.components.OperationalImpl retrieve
FINEST: Fetching Status: uid://X0000000000000066/X00000002
Jun 4, 2004 11:38:39 AM alma.scheduling.scheduler.Scheduler run
INFO: SCHEDULING: waiting til sb is done processing
Jun 4, 2004 11:38:39 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38824,localport=38798]
Jun 4, 2004 11:38:39 AM alma.archive.components.OperationalImpl retrieve
FINEST: Building Result: uid://X0000000000000066/X00000002
Jun 4, 2004 11:38:39 AM alma.scheduling.scheduler.Scheduler run
INFO: SCHEDULING: scheduler woken up!
Jun 4, 2004 11:38:39 AM alma.archive.components.OperationalImpl retrieve
FINEST: Schema: uid://X0000000000000064/X0000000f
Jun 4, 2004 11:38:39 AM alma.scheduling.master_scheduler.ALMATelescopeOperator sortSBs
INFO: SCHEDULING: sorting SBs!
Jun 4, 2004 11:38:39 AM alma.archive.components.OperationalImpl retrieve
FINEST: Type name: SchedBlock
Jun 4, 2004 11:38:39 AM alma.archive.components.OperationalImpl retrieve
FINEST: Version: 0
Jun 4, 2004 11:38:39 AM alma.archive.components.OperationalImpl retrieve
FINEST: Time Stamp: 2004-06-04T11:33:41.871
Jun 4, 2004 11:38:39 AM alma.archive.components.OperationalImpl retrieve
FINEST: Getting URI: uid://X0000000000000066/X00000002
Jun 4, 2004 11:38:39 AM alma.archive.components.OperationalImpl retrieve
FINEST: Fetching Xml: uid://X0000000000000066/X00000002
Jun 4, 2004 11:38:39 AM alma.archive.components.OperationalImpl retrieve
FINEST: Fetching Status: uid://X0000000000000066/X00000002
Jun 4, 2004 11:38:39 AM alma.archive.components.OperationalImpl query
FINE: Querying XPath: /sbl:SchedBlock Schema: SchedBlock
Jun 4, 2004 11:38:39 AM alma.archive.components.OperationalImpl query
FINEST: Schema location for SchedBlock is uid://X0000000000000064/X0000000f
Jun 4, 2004 11:38:39 AM alma.archive.components.OperationalImpl retrieve
FINEST: Building Result: uid://X0000000000000066/X00000002
Jun 4, 2004 11:38:39 AM alma.archive.components.OperationalImpl retrieve
FINEST: Schema: uid://X0000000000000064/X0000000f
Jun 4, 2004 11:38:39 AM alma.archive.components.OperationalImpl retrieve
FINEST: Type name: SchedBlock
Jun 4, 2004 11:38:39 AM alma.archive.components.OperationalImpl retrieve
FINEST: Version: 0
Jun 4, 2004 11:38:39 AM alma.archive.components.OperationalImpl retrieve
FINEST: Time Stamp: 2004-06-04T11:33:41.871
Jun 4, 2004 11:38:39 AM alma.archive.components.OperationalImpl update
FINEST: Updating: uid://X0000000000000066/X00000002
Jun 4, 2004 11:38:40 AM alma.archive.components.OperationalImpl query
FINE: Returning cursor to user
Jun 4, 2004 11:38:40 AM alma.acs.container.corba.AcsCorba activateOffShoot
FINER: offshoot of type 'alma.archive.components.CursorImpl' activated as a CORBA object.
Jun 4, 2004 11:38:40 AM alma.archive.components.OperationalImpl store
FINEST: Storing URI: uid://X0000000000000067/X00000005 Type: PipelineProcessingRequest
Jun 4, 2004 11:38:40 AM alma.archive.components.OperationalImpl store
FINEST: Schema Location for Type PipelineProcessingRequest is uid://X0000000000000064/X00000015
Jun 4, 2004 11:38:40 AM alma.archive.components.OperationalImpl store
FINEST: Stored: uid://X0000000000000067/X00000005 successfully
Jun 4, 2004 11:38:40 AM alma.scheduling.master_scheduler.ALMAArchive getPipelineProcessingRequest
INFO: ALMAArchive: getting ppr
Jun 4, 2004 11:38:40 AM alma.archive.components.OperationalImpl retrieve
FINEST: Getting URI: uid://X0000000000000067/X00000005
Jun 4, 2004 11:38:40 AM alma.archive.components.OperationalImpl retrieve
FINEST: Fetching Xml: uid://X0000000000000067/X00000005
Jun 4, 2004 11:38:40 AM alma.archive.components.OperationalImpl retrieve
FINEST: Fetching Status: uid://X0000000000000067/X00000005
Jun 4, 2004 11:38:40 AM alma.archive.components.OperationalImpl retrieve
FINEST: Building Result: uid://X0000000000000067/X00000005
Jun 4, 2004 11:38:40 AM alma.archive.components.OperationalImpl retrieve
FINEST: Schema: uid://X0000000000000064/X00000015
Jun 4, 2004 11:38:40 AM alma.archive.components.OperationalImpl retrieve
FINEST: Type name: PipelineProcessingRequest
Jun 4, 2004 11:38:40 AM alma.archive.components.OperationalImpl retrieve
FINEST: Version: 0
Jun 4, 2004 11:38:40 AM alma.archive.components.OperationalImpl retrieve
FINEST: Time Stamp: 2004-06-04T11:38:40.174
Jun 4, 2004 11:38:40 AM alma.acs.container.ContainerServicesImpl getComponent
FINE: will retrieve remote component 'SCIENCE_PIPELINE' using ACS Manager#get_component
Jun 4, 2004 11:38:40 AM alma.acs.container.ContainerServicesImpl getComponent
FINE: component SCIENCE_PIPELINE retrieved successfully.
Jun 4, 2004 11:38:40 AM alma.acs.container.ContainerSealant invoke
INFO: intercepted a call to 'SCIENCE_PIPELINE#processRequest'...
Jun 4, 2004 11:38:40 AM alma.pipelinescience.components.SciencePipelineImpl processRequest
INFO: SCIENCE_PIPELINE: processRequest() method initiated
Jun 4, 2004 11:38:40 AM alma.acs.container.ContainerSealant invoke
INFO: intercepted a call to 'IDENTIFIER_ARCHIVE#getIdNamespace'...
Jun 4, 2004 11:38:40 AM alma.acs.container.ContainerSealant invoke
INFO: returning from IDENTIFIER_ARCHIVE#getIdNamespace after 4 ms.
Jun 4, 2004 11:38:40 AM alma.pipelinescience.components.SciencePipelineImpl processRequest
INFO: SCIENCE_PIPELINE: identifier archive accessed
Jun 4, 2004 11:38:40 AM alma.pipelinescience.components.SciencePipelineImpl processRequest
INFO: SCIENCE_PIPELINE: listobject created
Jun 4, 2004 11:38:40 AM alma.acs.container.ContainerSealant invoke
INFO: returning from SCIENCE_PIPELINE#processRequest after 15 ms.
Jun 4, 2004 11:38:40 AM alma.scheduling.project_manager.ALMAPipeline processRequest
INFO: SCHEDULING: uid://X0000000000000068/X00000000
SCHEDULING: uid://X0000000000000068/X00000000
uid://X0000000000000067/X00000005 uid://X0000000000000068/X00000000 queued
uid://X0000000000000067/X00000005 uid://X0000000000000068/X00000000 queued
Jun 4, 2004 11:38:40 AM alma.scheduling.master_scheduler.ALMATelescopeOperator isCompletedSb
INFO: SCHEDULING: checking for non-complete SB
Jun 4, 2004 11:38:40 AM alma.archive.components.OperationalImpl query
FINE: Querying XPath: /sbl:SchedBlock Schema: SchedBlock
Jun 4, 2004 11:38:40 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38825,localport=38798]
Jun 4, 2004 11:38:40 AM alma.archive.components.OperationalImpl query
FINEST: Schema location for SchedBlock is uid://X0000000000000064/X0000000f
Jun 4, 2004 11:38:40 AM alma.archive.components.OperationalImpl query
FINE: Returning cursor to user
Jun 4, 2004 11:38:40 AM alma.acs.container.corba.AcsCorba activateOffShoot
FINER: offshoot of type 'alma.archive.components.CursorImpl' activated as a CORBA object.
Jun 4, 2004 11:38:40 AM alma.scheduling.master_scheduler.ALMATelescopeOperator isCompletedSb
INFO: SCHEDULING: checking for non-complete SB
Jun 4, 2004 11:38:40 AM alma.scheduling.scheduler.Scheduler run
INFO: SCHEDULING: No more SBs to process!
Jun 4, 2004 11:38:40 AM alma.scheduling.master_scheduler.SchedulingPublisher publishEvent
INFO: SCHEDULING: Event sent!
Jun 4, 2004 11:38:40 AM alma.scheduling.scheduler.Scheduler stop
INFO: SCHEDULING: About to stop scheduler.
Jun 4, 2004 11:38:40 AM alma.scheduling.master_scheduler.MasterScheduler run
INFO: SCHEDULING: MS Thread interrupted.
Jun 4, 2004 11:38:40 AM alma.scheduling.master_scheduler.MasterScheduler run
INFO: MSsleeping!
Jun 4, 2004 11:38:40 AM alma.scheduling.scheduler.Scheduler stop
INFO: SCHEDULING: Scheduler is stopped
Jun 4, 2004 11:38:41 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38826,localport=38798]
freeTime: 0.6324
Jun 4, 2004 11:38:42 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38827,localport=38798]
Jun 4, 2004 11:38:43 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38828,localport=38798]
Jun 4, 2004 11:38:44 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38829,localport=38798]
Jun 4, 2004 11:38:45 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38833,localport=38798]
Jun 4, 2004 11:38:46 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38834,localport=38798]
Jun 4, 2004 11:38:47 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38835,localport=38798]
Jun 4, 2004 11:38:48 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38836,localport=38798]
freeTime: 0.9549
Jun 4, 2004 11:38:49 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38837,localport=38798]
uid://X0000000000000067/X00000005 uid://X0000000000000068/X00000000 running
Jun 4, 2004 11:38:50 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38838,localport=38798]
Jun 4, 2004 11:38:51 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38839,localport=38798]
Jun 4, 2004 11:38:52 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38840,localport=38798]
Jun 4, 2004 11:38:53 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38841,localport=38798]
Jun 4, 2004 11:38:53 AM alma.acs.container.AcsContainer ping
FINER: ping received.
Jun 4, 2004 11:38:54 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38842,localport=38798]
Jun 4, 2004 11:38:55 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38843,localport=38798]
Jun 4, 2004 11:38:56 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38844,localport=38798]
Jun 4, 2004 11:38:57 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38845,localport=38798]
Jun 4, 2004 11:38:58 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38846,localport=38798]
freeTime: 0.9337
Jun 4, 2004 11:38:59 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38847,localport=38798]
Jun 4, 2004 11:39:00 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38848,localport=38798]
Jun 4, 2004 11:39:01 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38849,localport=38798]
Jun 4, 2004 11:39:02 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38850,localport=38798]
Jun 4, 2004 11:39:03 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38851,localport=38798]
Jun 4, 2004 11:39:04 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38852,localport=38798]
Jun 4, 2004 11:39:05 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38853,localport=38798]
Jun 4, 2004 11:39:06 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38854,localport=38798]
Jun 4, 2004 11:39:07 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38855,localport=38798]
Jun 4, 2004 11:39:08 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38856,localport=38798]
freeTime: 0.935
Jun 4, 2004 11:39:09 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38857,localport=38798]
uid://X0000000000000067/X00000005 uid://X0000000000000068/X00000000 completed
Jun 4, 2004 11:39:10 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38858,localport=38798]
Jun 4, 2004 11:39:11 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38859,localport=38798]
Jun 4, 2004 11:39:12 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38860,localport=38798]
Jun 4, 2004 11:39:13 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38861,localport=38798]
Jun 4, 2004 11:39:14 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38862,localport=38798]
Jun 4, 2004 11:39:15 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38863,localport=38798]
Jun 4, 2004 11:39:16 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38864,localport=38798]
Jun 4, 2004 11:39:17 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38865,localport=38798]
Jun 4, 2004 11:39:18 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38866,localport=38798]
freeTime: 0.9352
Jun 4, 2004 11:39:19 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38867,localport=38798]
Jun 4, 2004 11:39:20 AM alma.archive.components.OperationalImpl update
FINEST: Updating: uid://X0000000000000067/X00000005
Jun 4, 2004 11:39:20 AM alma.scheduling.receivers.PipelineEventReceiver receive
INFO: SCHEDULING: PipelineEnd event received!
Jun 4, 2004 11:39:20 AM alma.scheduling.receivers.PipelineEventReceiver push_structured_event
INFO: SCHEDULING: Got event from Pipeline
Jun 4, 2004 11:39:20 AM alma.pipelinescience.components.SciencePipelineImpl$RunProcess run
INFO: SCIENCE_PIPELINE: completion event issued
Jun 4, 2004 11:39:20 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38868,localport=38798]
Jun 4, 2004 11:39:21 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38869,localport=38798]
Jun 4, 2004 11:39:22 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38870,localport=38798]
Jun 4, 2004 11:39:23 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38871,localport=38798]
Jun 4, 2004 11:39:23 AM alma.acs.container.AcsContainer ping
FINER: ping received.
Jun 4, 2004 11:39:24 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38872,localport=38798]
Jun 4, 2004 11:39:25 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38873,localport=38798]
Jun 4, 2004 11:39:26 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38874,localport=38798]
Jun 4, 2004 11:39:27 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38875,localport=38798]
Jun 4, 2004 11:39:28 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38876,localport=38798]
freeTime: 0.9347
Jun 4, 2004 11:39:29 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38877,localport=38798]
Jun 4, 2004 11:39:30 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38878,localport=38798]
Jun 4, 2004 11:39:31 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38879,localport=38798]
Jun 4, 2004 11:39:32 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38880,localport=38798]
Jun 4, 2004 11:39:33 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38881,localport=38798]
Jun 4, 2004 11:39:34 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38882,localport=38798]
Jun 4, 2004 11:39:35 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38883,localport=38798]
Jun 4, 2004 11:39:36 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38884,localport=38798]
Jun 4, 2004 11:39:37 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38885,localport=38798]
Jun 4, 2004 11:39:38 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38886,localport=38798]
freeTime: 0.935
Jun 4, 2004 11:39:39 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38887,localport=38798]
Jun 4, 2004 11:39:40 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38888,localport=38798]
Jun 4, 2004 11:39:41 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38889,localport=38798]
Jun 4, 2004 11:39:42 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38890,localport=38798]
Jun 4, 2004 11:39:43 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38891,localport=38798]
Jun 4, 2004 11:39:44 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38892,localport=38798]
Jun 4, 2004 11:39:45 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38893,localport=38798]
Jun 4, 2004 11:39:46 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38894,localport=38798]
Jun 4, 2004 11:39:47 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38895,localport=38798]
Jun 4, 2004 11:39:48 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38899,localport=38798]
freeTime: 0.9348
Jun 4, 2004 11:39:49 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38900,localport=38798]
Jun 4, 2004 11:39:50 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38901,localport=38798]
Jun 4, 2004 11:39:51 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38902,localport=38798]
Jun 4, 2004 11:39:52 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38903,localport=38798]
Jun 4, 2004 11:39:53 AM alma.acs.container.AcsContainer ping
FINER: ping received.
Jun 4, 2004 11:39:53 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38904,localport=38798]
Jun 4, 2004 11:39:54 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38905,localport=38798]
Jun 4, 2004 11:39:55 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38906,localport=38798]
Jun 4, 2004 11:39:56 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38907,localport=38798]
Jun 4, 2004 11:39:57 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38908,localport=38798]
Jun 4, 2004 11:39:58 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38909,localport=38798]
freeTime: 0.9346
Jun 4, 2004 11:39:59 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38910,localport=38798]
Jun 4, 2004 11:40:00 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38911,localport=38798]
Jun 4, 2004 11:40:01 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38912,localport=38798]
Jun 4, 2004 11:40:02 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38913,localport=38798]
Jun 4, 2004 11:40:03 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38914,localport=38798]
Jun 4, 2004 11:40:04 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38915,localport=38798]
Jun 4, 2004 11:40:05 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38916,localport=38798]
Jun 4, 2004 11:40:06 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38917,localport=38798]
Jun 4, 2004 11:40:07 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38918,localport=38798]
Jun 4, 2004 11:40:08 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38919,localport=38798]
freeTime: 0.9348
Jun 4, 2004 11:40:09 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38920,localport=38798]
Jun 4, 2004 11:40:10 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38921,localport=38798]
Jun 4, 2004 11:40:11 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38922,localport=38798]
Jun 4, 2004 11:40:12 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38923,localport=38798]
Jun 4, 2004 11:40:13 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38924,localport=38798]
Jun 4, 2004 11:40:14 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38925,localport=38798]
Jun 4, 2004 11:40:15 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38926,localport=38798]
Jun 4, 2004 11:40:16 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38927,localport=38798]
Jun 4, 2004 11:40:17 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38928,localport=38798]
Jun 4, 2004 11:40:18 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38929,localport=38798]
freeTime: 0.9347
Jun 4, 2004 11:40:19 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38930,localport=38798]
Jun 4, 2004 11:40:20 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38931,localport=38798]
Jun 4, 2004 11:40:21 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38932,localport=38798]
Jun 4, 2004 11:40:22 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38933,localport=38798]
Jun 4, 2004 11:40:23 AM alma.acs.container.AcsContainer ping
FINER: ping received.
Jun 4, 2004 11:40:23 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38934,localport=38798]
Jun 4, 2004 11:40:24 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38935,localport=38798]
Jun 4, 2004 11:40:25 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38936,localport=38798]
Jun 4, 2004 11:40:26 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38937,localport=38798]
Jun 4, 2004 11:40:27 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38938,localport=38798]
Jun 4, 2004 11:40:28 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38939,localport=38798]
freeTime: 0.9351
Jun 4, 2004 11:40:29 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38940,localport=38798]
Jun 4, 2004 11:40:30 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38941,localport=38798]
Jun 4, 2004 11:40:31 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38942,localport=38798]
Jun 4, 2004 11:40:32 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38943,localport=38798]
Jun 4, 2004 11:40:33 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38944,localport=38798]
Jun 4, 2004 11:40:34 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38945,localport=38798]
Jun 4, 2004 11:40:35 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38946,localport=38798]
Jun 4, 2004 11:40:36 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38947,localport=38798]
Jun 4, 2004 11:40:38 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38948,localport=38798]
Jun 4, 2004 11:40:39 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38949,localport=38798]
freeTime: 0.9351
Jun 4, 2004 11:40:40 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38950,localport=38798]
Jun 4, 2004 11:40:41 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38951,localport=38798]
Jun 4, 2004 11:40:42 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38952,localport=38798]
Jun 4, 2004 11:40:43 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38953,localport=38798]
Jun 4, 2004 11:40:44 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38954,localport=38798]
Jun 4, 2004 11:40:45 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38955,localport=38798]
Jun 4, 2004 11:40:46 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38956,localport=38798]
Jun 4, 2004 11:40:47 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38957,localport=38798]
Jun 4, 2004 11:40:48 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38958,localport=38798]
Jun 4, 2004 11:40:49 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38959,localport=38798]
freeTime: 0.935
Jun 4, 2004 11:40:50 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38960,localport=38798]
Jun 4, 2004 11:40:51 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38961,localport=38798]
Jun 4, 2004 11:40:52 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38962,localport=38798]
Jun 4, 2004 11:40:53 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38963,localport=38798]
Jun 4, 2004 11:40:53 AM alma.acs.container.AcsContainer ping
FINER: ping received.
Jun 4, 2004 11:40:54 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38964,localport=38798]
Jun 4, 2004 11:40:55 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38965,localport=38798]
Jun 4, 2004 11:40:56 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38966,localport=38798]
Jun 4, 2004 11:40:57 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38967,localport=38798]
Jun 4, 2004 11:40:58 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38968,localport=38798]
Jun 4, 2004 11:40:59 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38969,localport=38798]
freeTime: 0.9347
Jun 4, 2004 11:41:00 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38970,localport=38798]
Jun 4, 2004 11:41:01 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38971,localport=38798]
Jun 4, 2004 11:41:02 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38972,localport=38798]
Jun 4, 2004 11:41:03 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38973,localport=38798]
Jun 4, 2004 11:41:04 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38974,localport=38798]
Jun 4, 2004 11:41:05 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38975,localport=38798]
Jun 4, 2004 11:41:06 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38976,localport=38798]
Jun 4, 2004 11:41:07 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38977,localport=38798]
Jun 4, 2004 11:41:08 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38978,localport=38798]
freeTime: 0.9354
Jun 4, 2004 11:41:09 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38979,localport=38798]
Jun 4, 2004 11:41:10 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38980,localport=38798]
Jun 4, 2004 11:41:11 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38981,localport=38798]
Jun 4, 2004 11:41:12 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38982,localport=38798]
Jun 4, 2004 11:41:13 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38983,localport=38798]
Jun 4, 2004 11:41:14 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38984,localport=38798]
Jun 4, 2004 11:41:15 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38985,localport=38798]
Jun 4, 2004 11:41:16 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38986,localport=38798]
Jun 4, 2004 11:41:17 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38987,localport=38798]
Jun 4, 2004 11:41:18 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38988,localport=38798]
freeTime: 0.9351
Jun 4, 2004 11:41:19 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38989,localport=38798]
Jun 4, 2004 11:41:20 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38990,localport=38798]
Jun 4, 2004 11:41:21 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38991,localport=38798]
Jun 4, 2004 11:41:22 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38992,localport=38798]
Jun 4, 2004 11:41:23 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38993,localport=38798]
Jun 4, 2004 11:41:23 AM alma.acs.container.AcsContainer ping
FINER: ping received.
Jun 4, 2004 11:41:24 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38994,localport=38798]
Jun 4, 2004 11:41:25 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38995,localport=38798]
Jun 4, 2004 11:41:26 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38996,localport=38798]
Jun 4, 2004 11:41:27 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38997,localport=38798]
Jun 4, 2004 11:41:28 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38998,localport=38798]
freeTime: 0.9351
Jun 4, 2004 11:41:29 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=38999,localport=38798]
Jun 4, 2004 11:41:30 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39000,localport=38798]
Jun 4, 2004 11:41:31 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39001,localport=38798]
Jun 4, 2004 11:41:32 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39002,localport=38798]
Jun 4, 2004 11:41:33 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39003,localport=38798]
Jun 4, 2004 11:41:34 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39004,localport=38798]
Jun 4, 2004 11:41:35 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39005,localport=38798]
Jun 4, 2004 11:41:36 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39006,localport=38798]
Jun 4, 2004 11:41:37 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39007,localport=38798]
Jun 4, 2004 11:41:38 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39008,localport=38798]
freeTime: 0.9348
Jun 4, 2004 11:41:39 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39009,localport=38798]
Jun 4, 2004 11:41:40 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39010,localport=38798]
Jun 4, 2004 11:41:41 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39011,localport=38798]
Jun 4, 2004 11:41:42 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39012,localport=38798]
Jun 4, 2004 11:41:43 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39013,localport=38798]
Jun 4, 2004 11:41:44 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39014,localport=38798]
Jun 4, 2004 11:41:45 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39015,localport=38798]
Jun 4, 2004 11:41:46 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39016,localport=38798]
Jun 4, 2004 11:41:47 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39017,localport=38798]
Jun 4, 2004 11:41:48 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39018,localport=38798]
freeTime: 0.9353
Jun 4, 2004 11:41:49 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39019,localport=38798]
Jun 4, 2004 11:41:50 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39020,localport=38798]
Jun 4, 2004 11:41:51 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39021,localport=38798]
Jun 4, 2004 11:41:52 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39022,localport=38798]
Jun 4, 2004 11:41:53 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39023,localport=38798]
Jun 4, 2004 11:41:53 AM alma.acs.container.AcsContainer ping
FINER: ping received.
Jun 4, 2004 11:41:54 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39024,localport=38798]
Jun 4, 2004 11:41:55 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39025,localport=38798]
Jun 4, 2004 11:41:56 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39026,localport=38798]
Jun 4, 2004 11:41:57 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39027,localport=38798]
Jun 4, 2004 11:41:58 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39028,localport=38798]
freeTime: 0.9353
Jun 4, 2004 11:41:59 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39029,localport=38798]
Jun 4, 2004 11:42:00 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39030,localport=38798]
Jun 4, 2004 11:42:01 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39031,localport=38798]
Jun 4, 2004 11:42:02 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39032,localport=38798]
Jun 4, 2004 11:42:03 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39033,localport=38798]
Jun 4, 2004 11:42:04 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39034,localport=38798]
Jun 4, 2004 11:42:05 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39035,localport=38798]
Jun 4, 2004 11:42:06 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39036,localport=38798]
Jun 4, 2004 11:42:07 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39037,localport=38798]
Jun 4, 2004 11:42:08 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39038,localport=38798]
freeTime: 0.9353
Jun 4, 2004 11:42:09 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39039,localport=38798]
Jun 4, 2004 11:42:10 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39040,localport=38798]
Jun 4, 2004 11:42:11 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39041,localport=38798]
Jun 4, 2004 11:42:12 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39042,localport=38798]
Jun 4, 2004 11:42:13 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39043,localport=38798]
Jun 4, 2004 11:42:14 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39044,localport=38798]
Jun 4, 2004 11:42:15 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39045,localport=38798]
Jun 4, 2004 11:42:16 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39046,localport=38798]
Jun 4, 2004 11:42:17 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39047,localport=38798]
Jun 4, 2004 11:42:18 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39048,localport=38798]
freeTime: 0.9346
Jun 4, 2004 11:42:19 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39049,localport=38798]
Jun 4, 2004 11:42:20 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39050,localport=38798]
Jun 4, 2004 11:42:21 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39051,localport=38798]
Jun 4, 2004 11:42:22 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39052,localport=38798]
Jun 4, 2004 11:42:23 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39053,localport=38798]
Jun 4, 2004 11:42:23 AM alma.acs.container.AcsContainer ping
FINER: ping received.
Jun 4, 2004 11:42:24 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39054,localport=38798]
Jun 4, 2004 11:42:25 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39055,localport=38798]
Jun 4, 2004 11:42:26 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39056,localport=38798]
Jun 4, 2004 11:42:27 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39057,localport=38798]
Jun 4, 2004 11:42:28 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39058,localport=38798]
freeTime: 0.9351
Jun 4, 2004 11:42:29 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39059,localport=38798]
Jun 4, 2004 11:42:30 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39060,localport=38798]
Jun 4, 2004 11:42:31 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39061,localport=38798]
Jun 4, 2004 11:42:32 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39062,localport=38798]
Jun 4, 2004 11:42:33 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39063,localport=38798]
Jun 4, 2004 11:42:34 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39064,localport=38798]
Jun 4, 2004 11:42:35 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39065,localport=38798]
Jun 4, 2004 11:42:36 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39066,localport=38798]
Jun 4, 2004 11:42:37 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39067,localport=38798]
Jun 4, 2004 11:42:38 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39068,localport=38798]
freeTime: 0.935
Jun 4, 2004 11:42:39 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39069,localport=38798]
Jun 4, 2004 11:42:40 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39070,localport=38798]
Jun 4, 2004 11:42:41 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39071,localport=38798]
Jun 4, 2004 11:42:42 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39072,localport=38798]
Jun 4, 2004 11:42:43 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39073,localport=38798]
Jun 4, 2004 11:42:44 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39074,localport=38798]
Jun 4, 2004 11:42:45 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39075,localport=38798]
Jun 4, 2004 11:42:46 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39076,localport=38798]
Jun 4, 2004 11:42:47 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39077,localport=38798]
Jun 4, 2004 11:42:48 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39078,localport=38798]
freeTime: 0.9349
Jun 4, 2004 11:42:49 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39079,localport=38798]
Jun 4, 2004 11:42:50 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39080,localport=38798]
Jun 4, 2004 11:42:51 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39081,localport=38798]
Jun 4, 2004 11:42:52 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39082,localport=38798]
Jun 4, 2004 11:42:53 AM alma.acs.container.AcsContainer ping
FINER: ping received.
Jun 4, 2004 11:42:53 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39083,localport=38798]
Jun 4, 2004 11:42:54 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39084,localport=38798]
Jun 4, 2004 11:42:55 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39085,localport=38798]
Jun 4, 2004 11:42:56 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39086,localport=38798]
Jun 4, 2004 11:42:57 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39087,localport=38798]
Jun 4, 2004 11:42:58 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39088,localport=38798]
freeTime: 0.9346
Jun 4, 2004 11:42:59 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39089,localport=38798]
Jun 4, 2004 11:43:00 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39090,localport=38798]
Jun 4, 2004 11:43:01 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39091,localport=38798]
Jun 4, 2004 11:43:02 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39092,localport=38798]
Jun 4, 2004 11:43:03 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39093,localport=38798]
Jun 4, 2004 11:43:04 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39094,localport=38798]
Jun 4, 2004 11:43:05 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39095,localport=38798]
Jun 4, 2004 11:43:06 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39096,localport=38798]
Jun 4, 2004 11:43:07 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39097,localport=38798]
Jun 4, 2004 11:43:08 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39098,localport=38798]
freeTime: 0.935
Jun 4, 2004 11:43:09 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39099,localport=38798]
Jun 4, 2004 11:43:10 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39100,localport=38798]
Jun 4, 2004 11:43:11 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39101,localport=38798]
Jun 4, 2004 11:43:12 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39102,localport=38798]
Jun 4, 2004 11:43:13 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39103,localport=38798]
Jun 4, 2004 11:43:14 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39104,localport=38798]
Jun 4, 2004 11:43:15 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39105,localport=38798]
Jun 4, 2004 11:43:16 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39106,localport=38798]
Jun 4, 2004 11:43:17 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39107,localport=38798]
Jun 4, 2004 11:43:18 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39108,localport=38798]
freeTime: 0.935
Jun 4, 2004 11:43:19 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39109,localport=38798]
Jun 4, 2004 11:43:20 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39110,localport=38798]
Jun 4, 2004 11:43:21 AM alma.scheduling.master_scheduler.ALMAArchive checkNewSB
INFO: SCHEDULING: checking for new sbs
Jun 4, 2004 11:43:21 AM alma.archive.components.OperationalImpl query
FINE: Querying XPath: /sbl:SchedBlock Schema: SchedBlock
Jun 4, 2004 11:43:21 AM alma.archive.components.OperationalImpl query
FINEST: Schema location for SchedBlock is uid://X0000000000000064/X0000000f
Jun 4, 2004 11:43:21 AM alma.scheduling.project_manager.ProjectManager run
INFO: SCHEDULING: PM woken up
Jun 4, 2004 11:43:21 AM alma.archive.components.OperationalImpl query
FINE: Returning cursor to user
Jun 4, 2004 11:43:21 AM alma.acs.container.corba.AcsCorba activateOffShoot
FINER: offshoot of type 'alma.archive.components.CursorImpl' activated as a CORBA object.
Jun 4, 2004 11:43:21 AM alma.scheduling.master_scheduler.ALMAArchive checkNewProjectDefs
INFO: SCHEDULING: checking for new project defs
Jun 4, 2004 11:43:21 AM alma.archive.components.OperationalImpl query
FINE: Querying XPath: /prj:ObsProject Schema: ObsProject
Jun 4, 2004 11:43:21 AM alma.archive.components.OperationalImpl query
FINEST: Schema location for ObsProject is uid://X0000000000000064/X0000000a
Jun 4, 2004 11:43:21 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39111,localport=38798]
Jun 4, 2004 11:43:22 AM alma.archive.components.OperationalImpl query
FINE: Returning cursor to user
Jun 4, 2004 11:43:22 AM alma.acs.container.corba.AcsCorba activateOffShoot
FINER: offshoot of type 'alma.archive.components.CursorImpl' activated as a CORBA object.
SCHEDULING: MB Action running
Jun 4, 2004 11:43:22 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39112,localport=38798]
Jun 4, 2004 11:43:23 AM alma.acs.container.AcsContainer ping
FINER: ping received.
Jun 4, 2004 11:43:23 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39113,localport=38798]
Jun 4, 2004 11:43:24 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39114,localport=38798]
Jun 4, 2004 11:43:25 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39115,localport=38798]
Jun 4, 2004 11:43:27 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39116,localport=38798]
Jun 4, 2004 11:43:28 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39117,localport=38798]
Jun 4, 2004 11:43:29 AM alma.archive.bulkstream.BulkStreamStore$SingleStreamListener run
FINE: Accepted connection: Socket[addr=/134.171.12.100,port=39118,localport=38798]
freeTime: 0.9334
freeTime: 0.935
Jun 4, 2004 11:43:40 AM alma.scheduling.master_scheduler.MasterScheduler run
INFO: SCHEDULING: MS Thread woken up.
Jun 4, 2004 11:43:40 AM alma.scheduling.master_scheduler.MasterScheduler run
INFO: MSsleeping!
freeTime: 0.9353
Jun 4, 2004 11:43:53 AM alma.acs.container.AcsContainer ping
FINER: ping received.
freeTime: 0.9353
freeTime: 0.9349
freeTime: 0.935
Jun 4, 2004 11:44:23 AM alma.acs.container.AcsContainer ping
FINER: ping received.
freeTime: 0.9352
freeTime: 0.9352
freeTime: 0.935
Jun 4, 2004 11:44:53 AM alma.acs.container.AcsContainer ping
FINER: ping received.
Jun 4, 2004 11:44:57 AM alma.acs.container.AcsContainer shutdown
INFO: received call to 'shutdown', action=2 (encryptedAction=512), gracefully=true.
Jun 4, 2004 11:44:57 AM alma.acs.container.AcsContainer logManagerRequest
FINE: received call to deactivate_components; handles = 16777221 16777223 16777230 16777219 16777217 16777222 16777229 16777220 16777231 16777218
Jun 4, 2004 11:44:57 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component SCIENCE_PIPELINE from OPERATIONAL to DESTROYING
Jun 4, 2004 11:44:57 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component BULKSTORE from OPERATIONAL to DESTROYING
Jun 4, 2004 11:44:57 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component ArrayController0 from OPERATIONAL to DESTROYING
Jun 4, 2004 11:44:57 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component IDENTIFIER_ARCHIVE from OPERATIONAL to DESTROYING
Jun 4, 2004 11:44:57 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component GETTELCALRESULTS1 from OPERATIONAL to DESTROYING
Jun 4, 2004 11:44:57 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component CONFIGURATION_VALIDATOR from OPERATIONAL to DESTROYING
Jun 4, 2004 11:44:57 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component MASTER_SCHEDULER from OPERATIONAL to DESTROYING
Jun 4, 2004 11:44:57 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component ControlSystem1 from OPERATIONAL to DESTROYING
Jun 4, 2004 11:44:57 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component DelayServer from OPERATIONAL to DESTROYING
Jun 4, 2004 11:44:57 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component ARCHIVE_CONNECTION from OPERATIONAL to DESTROYING
Jun 4, 2004 11:44:57 AM alma.acs.container.AcsContainer deactivate_components
FINER: deactivating component 'SCIENCE_PIPELINE' with handle 16777221
Jun 4, 2004 11:44:57 AM alma.acs.container.ComponentAdapter deactivateComponent
FINER: about to deactivate component SCIENCE_PIPELINE
Jun 4, 2004 11:44:57 AM alma.pipelinescience.components.SciencePipelineImpl cleanUp
INFO: SCIENCE_PIPELINE: cleanUp() method called
Jun 4, 2004 11:44:57 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component SCIENCE_PIPELINE from DESTROYING to DEFUNCT
Jun 4, 2004 11:44:57 AM alma.acs.container.ComponentAdapter deactivateComponent
FINER: done deactivating component SCIENCE_PIPELINE
Jun 4, 2004 11:44:57 AM alma.acs.container.AcsContainer deactivate_components
FINER: deactivating component 'BULKSTORE' with handle 16777223
Jun 4, 2004 11:44:57 AM alma.acs.container.ComponentAdapter deactivateComponent
FINER: about to deactivate component BULKSTORE
[ POA ComponentPOASCIENCE_PIPELINE - destroyed ]
[ POA RootPOA - destroyed ]
Jun 4, 2004 11:44:57 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component BULKSTORE from DESTROYING to DEFUNCT
Helper.run(): orb stopped
Jun 4, 2004 11:44:57 AM alma.acs.container.ComponentAdapter deactivateComponent
FINER: done deactivating component BULKSTORE
Jun 4, 2004 11:44:57 AM alma.acs.container.AcsContainer deactivate_components
FINER: deactivating component 'ArrayController0' with handle 16777230
Jun 4, 2004 11:44:57 AM alma.acs.container.ComponentAdapter deactivateComponent
FINER: about to deactivate component ArrayController0
Jun 4, 2004 11:44:57 AM alma.Control.arrayInterfaces.ArrayMonitorImpl cleanUp
INFO: ArrayMonitorImpl.cleanUp() called...
Jun 4, 2004 11:44:57 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component ArrayController0 from DESTROYING to DEFUNCT
Jun 4, 2004 11:44:57 AM alma.acs.container.ComponentAdapter deactivateComponent
FINER: done deactivating component ArrayController0
Jun 4, 2004 11:44:57 AM alma.acs.container.AcsContainer deactivate_components
FINER: deactivating component 'IDENTIFIER_ARCHIVE' with handle 16777219
Jun 4, 2004 11:44:57 AM alma.acs.container.ComponentAdapter deactivateComponent
FINER: about to deactivate component IDENTIFIER_ARCHIVE
[ POA ComponentPOAArrayController0 - destroyed ]
Jun 4, 2004 11:44:57 AM alma.archive.components.IdentifierImpl close
INFO: Disconnected from the identifier manager
Jun 4, 2004 11:44:57 AM alma.acs.container.ComponentStateManagerImpl setState
FINER: switching state of component IDENTIFIER_ARCHIVE from DESTROYING to DEFUNCT
[ POA ComponentPOABULKSTORE - destroyed ]
Jun 4, 2004 11:44:57 AM alma.acs.container.ComponentAdapter deactivateComponent
FINER: done deactivating component IDENTIFIER_ARCHIVE
Jun 4, 2004 11:44:57 AM alma.acs.container.AcsContainer deactivate_components
FINER: deactivating component 'GETTELCALRESULTS1' with handle 16777217
Jun 4, 2004 11:44:57 AM alma.acs.container.ComponentAdapter deactivateComponent
FINER: about to deactivate component GETTELCALRESULTS1
[ POA ComponentPOAIDENTIFIER_ARCHIVE - destroyed ]
Jun 4, 2004 11:44:57 AM alma.TelCalPublisher.GetTelCalResultsImpl.GetTelCalResultsImpl cleanUp
INFO: cleanUp() called.
Jun 4, 2004 11:44:57 AM alma.acs.container.ContainerServicesImpl releaseComponent
FINE: about to release component ARCHIVE_CONNECTION
Jun 4, 2004 11:44:57 AM alma.acs.container.AcsManagerProxy release_component
FINER: released 'ARCHIVE_CONNECTION' with the manager. Remaining clients: 1
Jun 4, 2004 11:44:57 AM alma.acs.container.ContainerServicesImpl releaseComponent
INFO: client 'GETTELCALRESULTS1' has successfully released a component with curl=ARCHIVE_CONNECTION
[ Connected to 134.171.12.100:3002 ]
Jun 4, 2004 11:44:58 AM alma.acs.container.AcsContainer disconnect
WARNING: Manager requests logout...
Jun 4, 2004 11:44:58 AM alma.acs.container.AcsManagerProxy logoutFromManager
FINE: about to log out from manager...
Jun 4, 2004 11:44:58 AM alma.acs.container.AcsManagerProxy logoutFromManager
WARNING: failed to log out gracefully from the manager...
org.omg.CORBA.NO_PERMISSION: This exception was reported by the server, it is only re-thrown here. vmcid: 0x0 minor code: 0 completed: No
at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
at java.lang.reflect.Constructor.newInstance(Constructor.java:274)
at org.jacorb.orb.SystemExceptionHelper.read(Unknown Source)
at org.jacorb.orb.connection.ReplyInputStream.checkExceptions(Unknown Source)
at org.jacorb.orb.Delegate.invoke(Unknown Source)
at org.omg.CORBA.portable.ObjectImpl._invoke(ObjectImpl.java:457)
at si.ijs.maci._ManagerStub.logout(_ManagerStub.java:141)
at alma.acs.container.AcsManagerProxy.logoutFromManager(AcsManagerProxy.java:336)
at alma.acs.container.AcsContainer.disconnect(AcsContainer.java:823)
at si.ijs.maci.ContainerPOA._invoke(ContainerPOA.java:107)
at org.jacorb.poa.RequestProcessor.invokeOperation(Unknown Source)
at org.jacorb.poa.RequestProcessor.process(Unknown Source)
at org.jacorb.poa.RequestProcessor.run(Unknown Source)
java.lang.NullPointerException
freeTime: 0.9238
Jun 4, 2004 11:45:08 AM alma.acs.container.AcsManagerProxy loginToManager
FINE: call to loginToManager(..) fails while shutting down.
Jun 4, 2004 11:45:08 AM alma.acs.container.AcsContainer disconnect
WARNING: Failed to re-login to the manager. Will shut down.
alma.acs.container.ContainerException: call to loginToManager(..) fails while shutting down.
at alma.acs.container.AcsManagerProxy.loginToManager(AcsManagerProxy.java:207)
at alma.acs.container.AcsContainer.loginToManager(AcsContainer.java:162)
at alma.acs.container.AcsContainer.disconnect(AcsContainer.java:838)
at si.ijs.maci.ContainerPOA._invoke(ContainerPOA.java:107)
at org.jacorb.poa.RequestProcessor.invokeOperation(Unknown Source)
at org.jacorb.poa.RequestProcessor.process(Unknown Source)
at org.jacorb.poa.RequestProcessor.run(Unknown Source)
Jun 4, 2004 11:45:08 AM alma.acs.container.AcsContainer shutdown
INFO: received call to 'shutdown', action=2 (encryptedAction=512), gracefully=true.
Jun 4, 2004 11:45:08 AM alma.acs.container.AcsContainer shutdown
FINE: call to shutdown() while shutting down will be ignored...
java.lang.NullPointerException
freeTime: 0.93
/alma/ACS-3.0/ACSSW/bin/acsStartJava: line 269: 5406 Killed java -classpath $CLASSPATH $JAVA_ENDORSED $JAVA_OPTIONS $JAVA_ORB -Duser.timezone=UTC -Dabeans.home=$ACS_ABEANS_CONFIG -DACS.manager=$MANAGER_REFERENCE -DORBInitRef.NameService=$ACS_NAME_SERVICE -DACS.repository=$ACS_INTERFACE_REPOSITORY -DACS.tmp=$ACS_TMP -DACS.baseport=$ACS_INSTANCE $1 $2 $3 $4 $5 $6 $7 $8 $9