Answered

Error message in xpoint file on PROOF Windows VMs, VMs are showing black screen in video recordings

We are getting the following error messages in the xpoint file on our PROOF Windows VMs, as a VMs are showing a black screen in video recordings.

OPS confirmed that there are no issues with autologin or with multiple users, please assist. Thanks,

 

2022-06-16 11:31:32 INFO reporting completed
2022-06-16 11:31:32 ERROR Failed to send a message
org.springframework.jms.UncategorizedJmsException: Uncategorized exception occurred during JMS processing; nested exception is javax.jms.JMSException: java.io.InterruptedIOException
at org.springframework.jms.support.JmsUtils.convertJmsAccessException(JmsUtils.java:311)
at org.springframework.jms.support.JmsAccessor.convertJmsAccessException(JmsAccessor.java:185)
at org.springframework.jms.core.JmsTemplate.execute(JmsTemplate.java:507)
at org.springframework.jms.core.JmsTemplate.send(JmsTemplate.java:584)
at org.springframework.jms.core.JmsTemplate.convertAndSend(JmsTemplate.java:691)
at com.subject7.proof.executor.cloud.RemoteReportService.send(RemoteReportService.java:108)
at com.subject7.proof.executor.cloud.RemoteReportService.lambda$startReportThread$0(RemoteReportService.java:69)
at java.lang.Thread.run(Thread.java:750)
Caused by: javax.jms.JMSException: java.io.InterruptedIOException
at org.apache.activemq.util.JMSExceptionSupport.create(JMSExceptionSupport.java:72)
at org.apache.activemq.ActiveMQConnection.syncSendPacket(ActiveMQConnection.java:1422)
at org.apache.activemq.ActiveMQConnection.syncSendPacket(ActiveMQConnection.java:1437)
at org.apache.activemq.TransactionContext.commit(TransactionContext.java:334)
at org.apache.activemq.ActiveMQSession.commit(ActiveMQSession.java:583)
at sun.reflect.GeneratedMethodAccessor129.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.springframework.jms.connection.CachingConnectionFactory$CachedSessionInvocationHandler.invoke(CachingConnectionFactory.java:383)
at com.sun.proxy.$Proxy70.commit(Unknown Source)
at org.springframework.jms.support.JmsUtils.commitIfNecessary(JmsUtils.java:218)
at org.springframework.jms.core.JmsTemplate.doSend(JmsTemplate.java:612)
at org.springframework.jms.core.JmsTemplate.lambda$send$3(JmsTemplate.java:586)
at org.springframework.jms.core.JmsTemplate.execute(JmsTemplate.java:504)
... 5 common frames omitted
Caused by: java.io.InterruptedIOException: null
at org.apache.activemq.transport.FutureResponse.dealWithInterrupt(FutureResponse.java:63)
at org.apache.activemq.transport.FutureResponse.getResult(FutureResponse.java:51)
at org.apache.activemq.transport.ResponseCorrelator.request(ResponseCorrelator.java:87)
at org.apache.activemq.ActiveMQConnection.syncSendPacket(ActiveMQConnection.java:1393)
... 17 common frames omitted
Caused by: java.lang.InterruptedException: null
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2014)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2048)
at java.util.concurrent.ArrayBlockingQueue.take(ArrayBlockingQueue.java:403)
at org.apache.activemq.transport.FutureResponse.getResult(FutureResponse.java:48)
... 19 common frames omitted
2022-06-16 11:31:32 INFO Cleanup started....
2022-06-16 11:31:32 WARN Could not close shared JMS Connection
javax.jms.JMSException: Disposed due to prior exception
at org.apache.activemq.util.JMSExceptionSupport.create(JMSExceptionSupport.java:72)
at org.apache.activemq.ActiveMQConnection.syncSendPacket(ActiveMQConnection.java:1422)
at org.apache.activemq.ActiveMQConnection.close(ActiveMQConnection.java:689)
at org.springframework.jms.connection.SingleConnectionFactory.closeConnection(SingleConnectionFactory.java:501)
at org.springframework.jms.connection.SingleConnectionFactory.resetConnection(SingleConnectionFactory.java:389)
at org.springframework.jms.connection.CachingConnectionFactory.resetConnection(CachingConnectionFactory.java:205)
at org.springframework.jms.connection.SingleConnectionFactory.onException(SingleConnectionFactory.java:367)
at org.springframework.jms.connection.SingleConnectionFactory$AggregatedExceptionListener.onException(SingleConnectionFactory.java:721)
at org.apache.activemq.ActiveMQConnection$5.run(ActiveMQConnection.java:1968)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:750)
Caused by: org.apache.activemq.transport.TransportDisposedIOException: Disposed due to prior exception
at org.apache.activemq.transport.ResponseCorrelator.onException(ResponseCorrelator.java:125)
at org.apache.activemq.transport.FutureResponse.dealWithInterrupt(FutureResponse.java:67)
at org.apache.activemq.transport.FutureResponse.getResult(FutureResponse.java:51)
at org.apache.activemq.transport.ResponseCorrelator.request(ResponseCorrelator.java:87)
at org.apache.activemq.ActiveMQConnection.syncSendPacket(ActiveMQConnection.java:1393)
at org.apache.activemq.ActiveMQConnection.syncSendPacket(ActiveMQConnection.java:1437)
at org.apache.activemq.TransactionContext.commit(TransactionContext.java:334)
at org.apache.activemq.ActiveMQSession.commit(ActiveMQSession.java:583)
at sun.reflect.GeneratedMethodAccessor129.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.springframework.jms.connection.CachingConnectionFactory$CachedSessionInvocationHandler.invoke(CachingConnectionFactory.java:383)
at com.sun.proxy.$Proxy70.commit(Unknown Source)
at org.springframework.jms.support.JmsUtils.commitIfNecessary(JmsUtils.java:218)
at org.springframework.jms.core.JmsTemplate.doSend(JmsTemplate.java:612)
at org.springframework.jms.core.JmsTemplate.lambda$send$3(JmsTemplate.java:586)
at org.springframework.jms.core.JmsTemplate.execute(JmsTemplate.java:504)
at org.springframework.jms.core.JmsTemplate.send(JmsTemplate.java:584)
at org.springframework.jms.core.JmsTemplate.convertAndSend(JmsTemplate.java:691)
at com.subject7.proof.executor.cloud.RemoteReportService.send(RemoteReportService.java:108)
at com.subject7.proof.executor.cloud.RemoteReportService.lambda$startReportThread$0(RemoteReportService.java:69)
... 1 common frames omitted
Caused by: java.io.InterruptedIOException: null
at org.apache.activemq.transport.FutureResponse.dealWithInterrupt(FutureResponse.java:63)
... 20 common frames omitted
Caused by: java.lang.InterruptedException: null
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2014)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2048)
at java.util.concurrent.ArrayBlockingQueue.take(ArrayBlockingQueue.java:403)
at org.apache.activemq.transport.FutureResponse.getResult(FutureResponse.java:48)
... 19 common frames omitted

0
5 comments
Avatar
Joseph Hamdan

Hi Stephen,

Would you please reboot the VMs and not access them, then try running a test to see what happens?

Regards,
Subject7 Team

0
Comment actions Permalink
Avatar
Stephen Akinpelumia

Hi Joseph, we did that; no change.

When the xpoint file is ran this is what we see..

ARGS: -server -Xms1G -Xmx2G -XX:NewSize=256M -jar ../lib/xpoint-9.5.5.jar --spring.config.location=classpath:application.properties,file:../conf/xpoint.properties
____ _ _ _ _____
/ ___| _ _ | |__ (_) ___ ___ | |_ |___ |
\___ \ | | | | | '_ \ | | / _ \ / __| | __| / /
___) | | |_| | | |_) | | | | __/ | (__ | |_ / /
|____/ \__,_| |_.__/ _/ | \___| \___| \__| /_/
|__/
2022-06-16 13:47:07 INFO The following profiles are active: cloud
2022-06-16 13:47:09 WARN SikuliX path: C:\Users\aamudharhs\AppData\Roaming\Sikulix
2022-06-16 13:47:09 WARN tessdata folder is deleted.
2022-06-16 13:47:09 WARN Target path for tessdata zip file with URL 'tessdata.zip' on copy: C:\Users\aamudharhs\AppData\Roaming\Sikulix\SikulixTesseract\tessdata\temp.zip
2022-06-16 13:47:09 WARN SikuliX tessdata is unpacked
2022-06-16 13:47:09 WARN SikuliX tessdata is installed
2022-06-16 13:47:10 ERROR Cannot determine the public IP. Cause: Server returned HTTP response code: 503 for URL: http://checkip.amazonaws.com

...we are also seeing errors after an execution has been completed...

 org.springframework.jms.listener.AbstractPollingMessageListenerContainer.receiveAndExecute(AbstractPollingMessageListenerContainer.java:257)
at org.springframework.jms.listener.DefaultMessageListenerContainer$AsyncMessageListenerInvoker.invokeListener(DefaultMessageListenerContainer.java:1189)
at org.springframework.jms.listener.DefaultMessageListenerContainer$AsyncMessageListenerInvoker.executeOngoingLoop(DefaultMessageListenerContainer.java:1179)
at org.springframework.jms.listener.DefaultMessageListenerContainer$AsyncMessageListenerInvoker.run(DefaultMessageListenerContainer.java:1076)
at java.lang.Thread.run(Thread.java:750)
Caused by: java.io.IOException: Cannot delete file: C:\Users\aamudharhs\.proof\artifacts\logs
at org.apache.commons.io.FileUtils.forceDelete(FileUtils.java:1344)
at org.apache.commons.io.FileUtils.cleanDirectory(FileUtils.java:324)
... 18 common frames omitted
Caused by: java.nio.file.FileSystemException: C:\Users\aamudharhs\.proof\artifacts\logs\1655395516090.txt: The process cannot access the file because it is being used by another process.

at sun.nio.fs.WindowsException.translateToIOException(WindowsException.java:86)
at sun.nio.fs.WindowsException.rethrowAsIOException(WindowsException.java:97)
at sun.nio.fs.WindowsException.rethrowAsIOException(WindowsException.java:102)
at sun.nio.fs.WindowsFileSystemProvider.implDelete(WindowsFileSystemProvider.java:273)
at sun.nio.fs.AbstractFileSystemProvider.deleteIfExists(AbstractFileSystemProvider.java:108)
at java.nio.file.Files.deleteIfExists(Files.java:1165)
at org.apache.commons.io.file.DeletingPathVisitor.visitFile(DeletingPathVisitor.java:161)
at org.apache.commons.io.file.DeletingPathVisitor.visitFile(DeletingPathVisitor.java:37)
at java.nio.file.Files.walkFileTree(Files.java:2670)
at java.nio.file.Files.walkFileTree(Files.java:2742)
at org.apache.commons.io.file.PathUtils.visitFileTree(PathUtils.java:971)
at org.apache.commons.io.file.PathUtils.deleteDirectory(PathUtils.java:434)
at org.apache.commons.io.file.PathUtils.delete(PathUtils.java:391)
at org.apache.commons.io.FileUtils.forceDelete(FileUtils.java:1341)
... 19 common frames omitted
2022-06-16 13:33:04 INFO Unable to completely clear tmp directory 'C:\Users\AAMUDH~1\AppData\Local\Temp\'. Reason: C:\Users\AAMUDH~1\AppData\Local\Temp
2022-06-16 13:33:04 INFO Cleanup completed

0
Comment actions Permalink
Avatar
Rex Feizi

From the above logs, looks like a multi-session issue. Please do the following: 

  1. Reboot the VM. 
  2. Stop the XPoint process, either by closing the terminal window or just finding the Java process in the Task Manager and terminating it. 
  3. Go to the user home directory and find the ".proof" folder and delete it. 
  4. If it doesn't allow you to delete, it's probably because there is an orphan chrome driver process. Go to task manager find it and terminate it.
  5. Restart the VM and follow the procedures you have in place for bringing up XPoint and disconnecting gracefully. 

Please let us know if this helps. 

 

0
Comment actions Permalink
Avatar
Stephen Akinpelumia

Thanks Rex, will keep you posted

 

0
Comment actions Permalink
Avatar
Stephen Akinpelumia

Update - the resolution Rex provided worked! Thanks Rex! 

0
Comment actions Permalink

Please sign in to leave a comment.