Latest post Mon, Dec 10 2018 3:59 PM by Rich Laws. 2 replies.
Page 1 of 1 (3 items)
Sort Posts: Previous Next
  • Wed, Dec 5 2018 3:15 PM

    • Rich Laws
    • Not Ranked
    • Joined on Wed, Oct 17 2012
    • Posts 67
    • Points 635

    Debug/Configuration advice sought for IPWS Exception - "The underlying connection was closed: An unexpected error occurred on a receive."

    Hi all,

    One of our clients is experiencing a random IPWS exception when the GetFileDetails method is invoked.  We haven't seen this before and are trying to help them resolve this.   IPWS/IPE version is 2017.2.   

    One thing that we have noticed, and this MAY BE a red herring so please don't take this as definitive, is that the assets this occurs with have media created by an EVS system in use at the client.  (said files are not compatible with AMT2 but this is a different issue).

    If the condition raising this exception can be explained, it may help us.  It appears to be connection related but we are not sure if metadata retrieval problems can affect connections or cause this exception.

    Our log shows this (error code directly from IPWS)

    12/5/2018 6:51:41 AM: ERROR: GetFileDetails exception: The underlying connection was closed: An unexpected error occurred on a receive.

    IPWS Log show this:  (no errors reported in the activity log)

    2018-12-05 06:47:39.115 [INFO ] http-8880-Processor23 nltek           c.a.i.w.a.dao.JXDKHeadframeDAO     :88    -- Checked out headframe file to: C:\Program Files\Avid\Interplay Web Services\.\apache-tomcat\temp\023375d626b44-060e2b347f7f-2a802896390570521502839HF.tmp 2018-12-05 06:47:42.226 [INFO ] http-8880-Processor21 nltek           c.a.i.w.a.d.JXDKCheckInOutDAO      :429   -- getLatest 2018-12-05 06:47:42.667 [INFO ] http-8880-Processor21 nltek           c.a.i.w.a.d.JXDKCheckInOutDAO      :436   -- getLatest done 2018-12-05 06:47:42.667 [INFO ] http-8880-Processor21 nltek           c.a.i.w.a.d.JXDKCheckInOutDAO      :442   -- Checked out file to: C:\Program Files\Avid\Interplay Web Services\.\apache-tomcat\temp\023375d626b44-060e2b347f7f-2a804668284956996499006.tmp 2018-12-05 06:51:41.261 [WARN ] http-8880-Processor22                 o.a.c.p.PhaseInterceptorChain      :345   -- Interceptor has thrown exception, unwinding now org.apache.cxf.interceptor.Fault: Could not send Message. at org.apache.cxf.interceptor.MessageSenderInterceptor$MessageSenderEndingInterceptor.handleMessage(MessageSenderInterceptor.java:64) [cxf.jar:2.2.4] at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:236) [cxf.jar:2.2.4] at org.apache.cxf.interceptor.OutgoingChainInterceptor.handleMessage(OutgoingChainInterceptor.java:74) [cxf.jar:2.2.4] at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:236) [cxf.jar:2.2.4] at org.apache.cxf.transport.ChainInitiationObserver.onMessage(ChainInitiationObserver.java:104) [cxf.jar:2.2.4] at org.apache.cxf.transport.servlet.ServletDestination.invoke(ServletDestination.java:98) [cxf.jar:2.2.4] at org.apache.cxf.transport.servlet.ServletController.invokeDestination(ServletController.java:392) [cxf.jar:2.2.4] at org.apache.cxf.transport.servlet.ServletController.invoke(ServletController.java:170) [cxf.jar:2.2.4] at org.apache.cxf.transport.servlet.AbstractCXFServlet.invoke(AbstractCXFServlet.java:142) [cxf.jar:2.2.4] at org.apache.cxf.transport.servlet.AbstractHTTPServlet.doPost(AbstractHTTPServlet.java:45) [cxf.jar:2.2.4] at javax.servlet.http.HttpServlet.service(HttpServlet.java:710) [servlet-api.jar:na] at org.apache.cxf.transport.servlet.AbstractHTTPServlet.service(AbstractHTTPServlet.java:101) [cxf.jar:2.2.4] at sun.reflect.GeneratedMethodAccessor67.invoke(Unknown Source) [na:na] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [na:1.8.0_102-u102] at java.lang.reflect.Method.invoke(Method.java:498) [na:1.8.0_102-u102] at org.apache.catalina.security.SecurityUtil$1.run(SecurityUtil.java:244) [catalina.jar:na] at java.security.AccessController.doPrivileged(Native Method) [na:1.8.0_102-u102] at javax.security.auth.Subject.doAsPrivileged(Subject.java:549) [na:1.8.0_102-u102] at org.apache.catalina.security.SecurityUtil.execute(SecurityUtil.java:276) [catalina.jar:na] at org.apache.catalina.security.SecurityUtil.doAsPrivilege(SecurityUtil.java:162) [catalina.jar:na] at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:262) [catalina.jar:na] at org.apache.catalina.core.ApplicationFilterChain.access$000(ApplicationFilterChain.java:52) [catalina.jar:na] at org.apache.catalina.core.ApplicationFilterChain$1.run(ApplicationFilterChain.java:171) [catalina.jar:na] at java.security.AccessController.doPrivileged(Native Method) [na:1.8.0_102-u102] at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:167) [catalina.jar:na] at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:210) [catalina.jar:na] at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:174) [catalina.jar:na] at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127) [catalina.jar:na] at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:117) [catalina.jar:na] at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:108) [catalina.jar:na] at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:151) [catalina.jar:na] at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:870) [tomcat-http.jar:na] at org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:665) [tomcat-http.jar:na] at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:528) [tomcat-util.jar:5.1] at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:81) [tomcat-util.jar:5.1] at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:685) [tomcat-util.jar:5.1] at java.lang.Thread.run(Thread.java:745) [na:1.8.0_102-u102] Caused by: org.apache.catalina.connector.ClientAbortException: null at org.apache.catalina.connector.OutputBuffer.doFlush(OutputBuffer.java:327) [na:na] at org.apache.catalina.connector.OutputBuffer.flush(OutputBuffer.java:293) [na:na] at org.apache.catalina.connector.Response.flushBuffer(Response.java:539) [na:na] at org.apache.catalina.connector.ResponseFacade$1.run(ResponseFacade.java:264) [na:na] at java.security.AccessController.doPrivileged(Native Method) [na:1.8.0_102-u102] at org.apache.catalina.connector.ResponseFacade.flushBuffer(ResponseFacade.java:259) [na:na] at org.apache.cxf.transport.http.AbstractHTTPDestination$WrappedOutputStream.close(AbstractHTTPDestination.java:564) [cxf.jar:2.2.4] at org.apache.cxf.transport.AbstractConduit.close(AbstractConduit.java:66) [cxf.jar:2.2.4] at org.apache.cxf.interceptor.MessageSenderInterceptor$MessageSenderEndingInterceptor.handleMessage(MessageSenderInterceptor.java:62) [cxf.jar:2.2.4] ... 36 common frames omitted Caused by: java.net.SocketException: Connection reset by peer: socket write error at java.net.SocketOutputStream.socketWrite0(Native Method) [na:1.8.0_102-u102] at java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:109) [na:1.8.0_102-u102] at java.net.SocketOutputStream.write(SocketOutputStream.java:153) [na:1.8.0_102-u102] at org.apache.coyote.http11.InternalOutputBuffer.realWriteBytes(InternalOutputBuffer.java:747) [na:na] at org.apache.tomcat.util.buf.ByteChunk.flushBuffer(ByteChunk.java:432) [na:na] at org.apache.coyote.http11.InternalOutputBuffer.flush(InternalOutputBuffer.java:305) [na:na] at org.apache.coyote.http11.Http11Processor.action(Http11Processor.java:992) [tomcat-http.jar:na] at org.apache.coyote.Response.action(Response.java:183) [na:na] at org.apache.catalina.connector.OutputBuffer.doFlush(OutputBuffer.java:322) [na:na] ... 44 common frames omitted 2018-12-05 06:51:41.263 [WARN ] http-8880-Processor22                 o.a.c.p.PhaseInterceptorChain      :345   -- Interceptor has thrown exception, unwinding now org.apache.cxf.binding.soap.SoapFault: Error writing to XMLStreamWriter. at org.apache.cxf.binding.soap.interceptor.SoapOutInterceptor$SoapOutEndingInterceptor.handleMessage(SoapOutInterceptor.java:274) [cxf.jar:2.2.4] at org.apache.cxf.binding.soap.interceptor.SoapOutInterceptor$SoapOutEndingInterceptor.handleMessage(SoapOutInterceptor.java:256) [cxf.jar:2.2.4] at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:236) [cxf.jar:2.2.4] at org.apache.cxf.interceptor.AbstractFaultChainInitiatorObserver.onMessage(AbstractFaultChainInitiatorObserver.java:96) [cxf.jar:2.2.4] at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:284) [cxf.jar:2.2.4] at org.apache.cxf.interceptor.OutgoingChainInterceptor.handleMessage(OutgoingChainInterceptor.java:74) [cxf.jar:2.2.4] at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:236) [cxf.jar:2.2.4] at org.apache.cxf.transport.ChainInitiationObserver.onMessage(ChainInitiationObserver.java:104) [cxf.jar:2.2.4] at org.apache.cxf.transport.servlet.ServletDestination.invoke(ServletDestination.java:98) [cxf.jar:2.2.4] at org.apache.cxf.transport.servlet.ServletController.invokeDestination(ServletController.java:392) [cxf.jar:2.2.4] at org.apache.cxf.transport.servlet.ServletController.invoke(ServletController.java:170) [cxf.jar:2.2.4] at org.apache.cxf.transport.servlet.AbstractCXFServlet.invoke(AbstractCXFServlet.java:142) [cxf.jar:2.2.4] at org.apache.cxf.transport.servlet.AbstractHTTPServlet.doPost(AbstractHTTPServlet.java:45) [cxf.jar:2.2.4] at javax.servlet.http.HttpServlet.service(HttpServlet.java:710) [servlet-api.jar:na] at org.apache.cxf.transport.servlet.AbstractHTTPServlet.service(AbstractHTTPServlet.java:101) [cxf.jar:2.2.4] at sun.reflect.GeneratedMethodAccessor67.invoke(Unknown Source) [na:na] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [na:1.8.0_102-u102] at java.lang.reflect.Method.invoke(Method.java:498) [na:1.8.0_102-u102] at org.apache.catalina.security.SecurityUtil$1.run(SecurityUtil.java:244) [catalina.jar:na] at java.security.AccessController.doPrivileged(Native Method) [na:1.8.0_102-u102] at javax.security.auth.Subject.doAsPrivileged(Subject.java:549) [na:1.8.0_102-u102] at org.apache.catalina.security.SecurityUtil.execute(SecurityUtil.java:276) [catalina.jar:na] at org.apache.catalina.security.SecurityUtil.doAsPrivilege(SecurityUtil.java:162) [catalina.jar:na] at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:262) [catalina.jar:na] at org.apache.catalina.core.ApplicationFilterChain.access$000(ApplicationFilterChain.java:52) [catalina.jar:na] at org.apache.catalina.core.ApplicationFilterChain$1.run(ApplicationFilterChain.java:171) [catalina.jar:na] at java.security.AccessController.doPrivileged(Native Method) [na:1.8.0_102-u102] at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:167) [catalina.jar:na] at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:210) [catalina.jar:na] at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:174) [catalina.jar:na] at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127) [catalina.jar:na] at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:117) [catalina.jar:na] at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:108) [catalina.jar:na] at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:151) [catalina.jar:na] at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:870) [tomcat-http.jar:na] at org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:665) [tomcat-http.jar:na] at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:528) [tomcat-util.jar:5.1] at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:81) [tomcat-util.jar:5.1] at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:685) [tomcat-util.jar:5.1] at java.lang.Thread.run(Thread.java:745) [na:1.8.0_102-u102] Caused by: javax.xml.stream.XMLStreamException: No open start element, when trying to write end element at com.ctc.wstx.sw.BaseStreamWriter.throwOutputError(BaseStreamWriter.java:1473) [wstx-asl.jar:3.2.8] at com.ctc.wstx.sw.BaseStreamWriter.reportNwfStructure(BaseStreamWriter.java:1502) [wstx-asl.jar:3.2.8] at com.ctc.wstx.sw.BaseNsStreamWriter.doWriteEndTag(BaseNsStreamWriter.java:606) [wstx-asl.jar:3.2.8] at com.ctc.wstx.sw.BaseNsStreamWriter.writeEndElement(BaseNsStreamWriter.java:282) [wstx-asl.jar:3.2.8] at org.apache.cxf.binding.soap.interceptor.SoapOutInterceptor$SoapOutEndingInterceptor.handleMessage(SoapOutInterceptor.java:266) [cxf.jar:2.2.4] ... 39 common frames omitted 2018-12-05 07:07:58.593 [INFO ] http-8880-Processor24 nltek           c.a.i.w.a.dao.JXDKHeadframeDAO     :88    -- Checked out headframe file to: C:\Program Files\Avid\Interplay Web Services\.\apache-tomcat\temp\0233839315057-060e2b347f7f-2a803200572284010379996HF.tmp
  • Fri, Dec 7 2018 5:37 PM In reply to

    • handtruck
    • Not Ranked
    • Joined on Mon, Apr 21 2014
    • Posts 56
    • Points 605

    Re: Debug/Configuration advice sought for IPWS Exception - "The underlying connection was closed: An unexpected error occurred on a receive."

    Hey Rich,

    My first instinct is to say that the media files have a weird character in them preventing the clean return from IPWS.

    Also note that for better or worse, GetFileDetails does prompt a call to the Media Indexer which I've seen cause issues in the past, but since it seems you have some success, it's probably not a config issue.

  • Mon, Dec 10 2018 3:59 PM In reply to

    • Rich Laws
    • Not Ranked
    • Joined on Wed, Oct 17 2012
    • Posts 67
    • Points 635

    Re: Debug/Configuration advice sought for IPWS Exception - "The underlying connection was closed: An unexpected error occurred on a receive."

    Hi,

    Thanks for the reply and added information on GetFileDetails.  I agree that this does not seem to be a general configuration issue (thousands of operations with other assets - no issues).  So far we have traced this to a handful of assets whose mxf media files were created by an EVS system.  We've had to deal with these media files in another one of our applications that uses AMT2 by patching the header to add a piece of metadata AMT2 requires.  This particular issue doesn't have anything to do with AMT2 - just IPWS.  It may be some strange effect as a result of the EVS media (or not).  Still gathering information and trying to see if it ever happens with Avid created media.

    Rich

Page 1 of 1 (3 items)

© Copyright 2011 Avid Technology, Inc.  Terms of Use |  Privacy Policy |  Site Map |  Find a Reseller