URGENT: OD Receiver Service dies intermittently...
Good afternoon,
We are experiencing intermittent OD receiver service failures on both of our production servers. The OD service stops unexpectedly, causing a great inconvenience to our TeamSite user community and hindering our ability to promote content to the production servers. The most recent cases have happened to a specific production server and after analyzing the logs, we noticed the following errors:
Base log (hostname_odbase.log):
ENG: 2003-10-09 17:20:26 EDT GMT-04:00 Starting job m6 for deployment fileListDeploy with parameters: {WEEKINV=0, STARTTIME=Thu Oct 09 17:20:26 EDT 2003, DAYINV=0, MONTHDAYS=null, HOURINV=0, DEPLOYNAME=fileListDeploy, JOBID=m6, REPAIR=false, STARTDEPLOY=true, DESCRIPTION=, VERIFY=false, NO_RECUR=NO_RECUR, DEPLOYSUFFIX=, ENDTIME=null, OWNER=SYSTEM, WEEKDAY=null, RECURSET=NO_RECUR, PAD6=null, PAD5=null, SYNCH=true, PAD4=null, KEYVALUES=area=Y:/default/main/Intranet/Financial/STAGING
filelist=e:/apps/INTERW~1/iw-home/custom/temp/file_list_for_task_47581
farm=prod
target=E:/data
, REPEAT=false, PAD3=null, PAD2=null, PAD1=null, YEARINV=0, LOGLEVEL=UNSPECIFIED, MINUTEINV=0}
ENG: 2003-10-09 17:20:26 EDT GMT-04:00 Synchronous deployment. MacroDeploy Session[m6,fileListDeploy] started
ENG: 2003-10-09 17:20:26 EDT GMT-04:00 Adding session m6(fileListDeploy)
API: 2003-10-09 17:20:44 EDT GMT-04:00 Requested start of deployment: fileListDeploy
API: 2003-10-09 17:20:44 EDT GMT-04:00 Calling server to start deployment fileListDeploy
ENG: 2003-10-09 17:20:44 EDT GMT-04:00 Received request to start deployment fileListDeploy.
ENG: 2003-10-09 17:20:44 EDT GMT-04:00 ***WARNING - There is already a deployment 'fileListDeploy' running. Request to start it now cannot be done. Queuing deployment to run later.
ENG: 2003-10-09 17:20:44 EDT GMT-04:00 ***WARNING - Failed starting deployment: fileListDeploy
API: 2003-10-09 17:21:04 EDT GMT-04:00 Requested start of deployment: fileListDeploy
API: 2003-10-09 17:21:04 EDT GMT-04:00 Calling server to start deployment fileListDeploy
ENG: 2003-10-09 17:21:04 EDT GMT-04:00 Received request to start deployment fileListDeploy.
ENG: 2003-10-09 17:21:04 EDT GMT-04:00 ***WARNING - There is already a deployment 'fileListDeploy' running. Request to start it now cannot be done. Queuing deployment to run later.
ENG: 2003-10-09 17:21:04 EDT GMT-04:00 ***WARNING - Failed starting deployment: fileListDeploy
ENG: 2003-10-09 17:21:47 EDT GMT-04:00 Removing session m6(fileListDeploy)
ENG: 2003-10-09 17:21:47 EDT GMT-04:00 Pending deployment fileListDeploy detected. Will start up after this one is completed
ENG: 2003-10-09 17:21:47 EDT GMT-04:00 Starting pended deployment [fileListDeploy]
ENG: 2003-10-09 17:21:47 EDT GMT-04:00 Received request to start deployment fileListDeploy.
ENG: 2003-10-09 17:21:47 EDT GMT-04:00 Starting job m7 for deployment fileListDeploy with parameters: {WEEKINV=0, STARTTIME=Thu Oct 09 17:21:47 EDT 2003, PENDSOURCE=iw_clt, DAYINV=0, MONTHDAYS=null, HOURINV=0, DEPLOYNAME=fileListDeploy, JOBID=m7, REPAIR=false, STARTDEPLOY=true, DESCRIPTION=, VERIFY=false, NO_RECUR=NO_RECUR, DEPLOYSUFFIX=, ENDTIME=null, OWNER=SYSTEM, WEEKDAY=null, RECURSET=NO_RECUR, PAD6=null, PAD5=null, SYNCH=true, PAD4=null, KEYVALUES=area=Y:/default/main/Intranet/Financial/STAGING
filelist=e:/apps/INTERW~1/iw-home/custom/temp/file_list_for_task_47605
farm=prod
target=E:/data
, REPEAT=false, PAD3=null, PAD2=null, PAD1=null, YEARINV=0, LOGLEVEL=UNSPECIFIED, MINUTEINV=0}
ENG: 2003-10-09 17:21:47 EDT GMT-04:00 Synchronous deployment. MacroDeploy Session[m7,fileListDeploy] started
ENG: 2003-10-09 17:21:47 EDT GMT-04:00 Adding session m7(fileListDeploy)
We are using the same OD definition for promoting content from different areas of the branch structure, and in doing so, have enabled the queuing option. This would allow OD to queue the requests sequentially, instead of terminating the requests if a deployment of the same definition was already running.
Base log (scr.fileListDeploy.log):
ENG: 2003-10-09 22:52:55 EDT GMT-04:00 Next deployment flag for intraprod01 is false
ENG: 2003-10-09 22:52:55 EDT GMT-04:00 Internal deployment name is .fileListDeploy.fileListDeploy.intraprod01
ENG: 2003-10-09 22:52:55 EDT GMT-04:00 Got internal config file for .fileListDeploy.fileListDeploy.intraprod01
ENG: 2003-10-09 22:52:55 EDT GMT-04:00 Starting internal deployment intraprod02.fileListDeploy, Thread-98
ENG: 2003-10-09 22:52:55 EDT GMT-04:00 Starting internal deployment intraprod01.fileListDeploy, Thread-99
ENG: 2003-10-09 22:52:55 EDT GMT-04:00 Waiting for 2 children to complete phases
ENG: 2003-10-09 22:52:55 EDT GMT-04:00 EXITING [setup] ENTERING [dotask]
ENG: 2003-10-09 22:53:30 EDT GMT-04:00 EXITING [dotask] ENTERING [commit]
ENG: 2003-10-09 22:53:31 EDT GMT-04:00 1 children failed
ENG: 2003-10-09 22:53:31 EDT GMT-04:00 1 children redirected
ENG: 2003-10-09 22:53:31 EDT GMT-04:00 EXITING [commit] ENTERING [rollback]
ENG: 2003-10-09 22:53:33 EDT GMT-04:00 EXITING [rollback] ENTERING [cleanup]
ENG: 2003-10-09 22:5
Entered By: Yama Shalizi 10/10/2003 10:10:41 AM
3:33 EDT GMT-04:00 EXITING [cleanup] ENTERING [task-failed]
ENG: 2003-10-09 22:53:33 EDT GMT-04:00 Signalling completion to MacroDeploy
ENG: 2003-10-09 22:53:33 EDT GMT-04:00 All 2 children completed their phases
Base log (acr.fileListDepoy.fileistDeploy.BASESERVER.to.RECEIVERSERVER.log):
client: Filelist=[E:\apps\INTERW~1\iw-home\custom\temp\file_list_for_task_47875]
client: Sending [.\Financial\Restricted\IncomeStatements\PL03fcst.xls] [reason file_list] client: OK
client: COMMITTING Transactional Deployment
Receiver-ERROR: ERROR: Renaming [E:\data\Financial\Restricted\IncomeStatements\PL03fcst.xls.iwnew] -> [E:\data\Financial\Restricted\IncomeStatements\PL03fcst.xls]
.
ERROR: Failed to deploy directory (.)
client: ROLLBACK Transactional Deployment
ERROR: Could not read next directive.
Receiver-ERROR: .
ERROR: Failed to deploy directory (.)
ERROR: Could not read content size from remote.
ERROR: Could not read server status, client OK.
....
ENG: 2003-10-10 07:31:27 EDT GMT-04:00 Thread-124 exiting initial
ENG: 2003-10-10 07:31:27 EDT GMT-04:00 Thread-124 entering setup
ENG: 2003-10-10 07:31:27 EDT GMT-04:00 Thread-124 Connecting to host=intraprod02 port=20014
ENG: 2003-10-10 07:35:04 EDT GMT-04:00 Thread-124 ***ERROR - Job ID=m28Deployment=fileListDeploy
Base: failed creating socket, got null.
ENG: 2003-10-10 07:35:04 EDT GMT-04:00 Thread-124 exiting setup
ENG: 2003-10-10 07:35:04 EDT GMT-04:00 Thread-124 entering awaiting-cleanup
ENG: 2003-10-10 07:35:04 EDT GMT-04:00 Thread-124 still waiting for 0
ENG: 2003-10-10 07:35:04 EDT GMT-04:00 Thread-124 exiting awaiting-cleanup
.....
We do have two NIC cards on the production servers, however, only one of the NIC cards is active. I did find a KB article related to that error message, but once again, we only have one NIC card active so I'm not sure if the KB applies here.
Once we identified the OD issue, the service(s) had to be restart for us to continue forward.
Considering this issue is affecting our production environment, your most prompted attention/assistance is appreciated!
I will send an email to support.interwoven.com attaching all necessary logs and config files. Time is of the essence and we would appreciated this case be escalated to the highest support level.
Regards,
Yama Shalizi