Skip to content

Instantly share code, notes, and snippets.

@kvc-code
Created December 30, 2015 01:48
Show Gist options
  • Save kvc-code/73b51ef1f5bf7a6af7f7 to your computer and use it in GitHub Desktop.
Save kvc-code/73b51ef1f5bf7a6af7f7 to your computer and use it in GitHub Desktop.
CM crash on CDA receipt
This is the client log:
example.login()
DebugConsoleImpl.scala:17 2015-12-29T17:28:33.674-08:00 Info msg="logging in..."
DebugConsoleImpl.scala:17 2015-12-29T17:28:33.682-08:00 Info Begin CortextClientImpl.loginForCortextIssuer:
DebugConsoleImpl.scala:17 2015-12-29T17:28:33.684-08:00 Info Begin CortextClientImpl.authAndRegisterDeviceApplication:
DebugConsoleImpl.scala:17 2015-12-29T17:28:33.688-08:00 Info Begin RemoteCapiHttpService.authAndRegisterDeviceApplication:
DebugConsoleImpl.scala:17 2015-12-29T17:28:33.723-08:00 Debug Begin HTTPClientImpl.post: uri="http://127.0.0.1:8989/v1/authAndRegisterDeviceApplication", payload="HTTPPayload(ContentType(application/json), data={\"operationId\":\"TpgsyUXlRMKQCoDoaDuX3SWU\",\"creds\":{\"username\":\"bob.smith@ngauth.net\",\"password\":\"[[TODO: make me not show up in logs via the HTTP payload]]\"},\"deviceApplicationInfo\":{\"deviceInfo\":{\"osInfo\":{\"name\":\"Mac OS\",\"version\":\"10.11.2\"},\"runtimeInfo\":{\"name\":\"Chrome\",\"version\":\"47.0.2526.106\"},\"hardwareInfo\":{\"vendor\":\"SomeHardwareVendor\",\"model\":\"SomeHardwareModel\",\"version\":\"SomeHardwareVersion\"},\"name\":\"SomeDevice\"},\"appInfo\":{\"orgName\":\"Imprivata\",\"appName\":\"Cortext\",\"appVersion\":\"4.0.0\"}}})"
DebugConsoleImpl.scala:17 2015-12-29T17:28:33.751-08:00 Debug Future HTTPClientImpl.post:
DebugConsoleImpl.scala:17 2015-12-29T17:28:33.754-08:00 Info Future RemoteCapiHttpService.authAndRegisterDeviceApplication:
DebugConsoleImpl.scala:17 2015-12-29T17:28:33.755-08:00 Info Future CortextClientImpl.authAndRegisterDeviceApplication:
DebugConsoleImpl.scala:17 2015-12-29T17:28:33.758-08:00 Info Future CortextClientImpl.loginForCortextIssuer:
$c_Lcom_imprivata_cortext_client_JsCortextClient {clientConfig$1: $c_Lcom_imprivata_cortext_client_config_ClientConfig, serviceProviders$1: $c_Lcom_imprivata_cortext_client_spi_ServiceProviders, com$imprivata$cortext$client$JsCortextClient$$clientImpl$f: $c_Lcom_imprivata_cortext_client_impl_CortextClientImpl, logger$1: $c_Lcom_imprivata_cortext_client_logging_JsClientLogging}
DebugConsoleImpl.scala:17 2015-12-29T17:28:36.030-08:00 Debug End HTTPClientImpl.post(successResult="HTTPSuccessResponse(HTTPOk(200), HTTPPayload(ContentType(application/json), data={\"accessToken\":\"eyJzdWIiOiJib2Iuc21pdGhAbmdhdXRoLm5ldCJ9\",\"deviceApplicationId\":\"620572340753\"}))"):
DebugConsoleImpl.scala:17 2015-12-29T17:28:36.061-08:00 Info End RemoteCapiHttpService.authAndRegisterDeviceApplication(successResult="AuthAndRegDevAppSuccessResult(CortextBearerToken(eyJzdWIiOiJib2Iuc21pdGhAbmdhdXRoLm5ldCJ9),com.imprivata.cortext.capiprotocol.DeviceApplicationId@7cfe7281)"):
DebugConsoleImpl.scala:17 2015-12-29T17:28:36.087-08:00 Info End CortextClientImpl.authAndRegisterDeviceApplication(successResult="LoggedInState(CortextBearerToken(eyJzdWIiOiJib2Iuc21pdGhAbmdhdXRoLm5ldCJ9),com.imprivata.cortext.capiprotocol.DeviceApplicationId@7cfe7281)"):
DebugConsoleImpl.scala:17 2015-12-29T17:28:36.119-08:00 Info End CortextClientImpl.loginForCortextIssuer(successResult="LoggedInState(CortextBearerToken(eyJzdWIiOiJib2Iuc21pdGhAbmdhdXRoLm5ldCJ9),com.imprivata.cortext.capiprotocol.DeviceApplicationId@7cfe7281)"):
DebugConsoleImpl.scala:17 2015-12-29T17:28:36.134-08:00 Info msg="login complete. starting session..."
DebugConsoleImpl.scala:17 2015-12-29T17:28:36.135-08:00 Info Begin CortextClientImpl.newSession:
DebugConsoleImpl.scala:17 2015-12-29T17:28:36.141-08:00 Debug Begin RemoteCapiWebSocketService.connect:
DebugConsoleImpl.scala:17 2015-12-29T17:28:36.186-08:00 Debug Begin(
op = ConnectWebSocket(sessionId = CortextSessionId(value = "4pKCoEtI9XNZsEvZJxXCEYZU")),
details = ()
)
DebugConsoleImpl.scala:17 2015-12-29T17:28:36.215-08:00 Debug Begin(
op = ConnectWebSocket(sessionId = CortextSessionId(value = "4pKCoEtI9XNZsEvZJxXCEYZU")),
details = ()
)
DebugConsoleImpl.scala:17 2015-12-29T17:28:36.247-08:00 Debug ConnectionStateTransition(
sessionId = CortextSessionId(value = "4pKCoEtI9XNZsEvZJxXCEYZU"),
prevState = Closed,
newState = ConnectingWebSocket
)
DebugConsoleImpl.scala:17 2015-12-29T17:28:36.250-08:00 Debug Future RemoteCapiWebSocketService.connect:
DebugConsoleImpl.scala:17 2015-12-29T17:28:36.253-08:00 Info Future CortextClientImpl.newSession:
DebugConsoleImpl.scala:17 2015-12-29T17:28:37.241-08:00 Info WebSocketConnected(
sessionId = CortextSessionId(value = "4pKCoEtI9XNZsEvZJxXCEYZU"),
url = ws://127.0.0.1:8888/ws/v1
)
DebugConsoleImpl.scala:17 2015-12-29T17:28:37.253-08:00 Debug End(
op = ConnectWebSocket(sessionId = CortextSessionId(value = "4pKCoEtI9XNZsEvZJxXCEYZU")),
result = Good(com.imprivata.cortext.client.impl.net.PlatformWebSocketImpl@c)
)
DebugConsoleImpl.scala:17 2015-12-29T17:28:37.267-08:00 Debug ConnectionStateTransition(
sessionId = CortextSessionId(value = "4pKCoEtI9XNZsEvZJxXCEYZU"),
prevState = ConnectingWebSocket,
newState = AwaitingConnectDeviceApplicationResponse
)
DebugConsoleImpl.scala:17 2015-12-29T17:28:37.325-08:00 Info Begin(
op = RemoteCapiSendMessage(
messageType = "ConnectDeviceApplicationRequest",
coapOptions = CoapOptions(
options = List(AuthTokenOption(tokenData = "eyJzdWIiOiJib2Iuc21pdGhAbmdhdXRoLm5ldCJ9"))
),
correlationToken = Some(5072652930224448037),
sessionId = CortextSessionId(value = "4pKCoEtI9XNZsEvZJxXCEYZU")
),
details = ()
)
DebugConsoleImpl.scala:17 2015-12-29T17:28:37.472-08:00 Info End(
op = RemoteCapiSendMessage(
messageType = "ConnectDeviceApplicationRequest",
coapOptions = CoapOptions(
options = List(AuthTokenOption(tokenData = "eyJzdWIiOiJib2Iuc21pdGhAbmdhdXRoLm5ldCJ9"))
),
correlationToken = Some(5072652930224448037),
sessionId = CortextSessionId(value = "4pKCoEtI9XNZsEvZJxXCEYZU")
),
result = Good(undefined)
)
===============================================================
And this is the server crash resulting from the client attempt to start-session
[info] Running com.imprivata.cortext.server.connection.Main
[DEBUG] [12/29/2015 17:27:30.599] [run-main-0] [EventStream(akka://ConnectionManagement)] logger log1-Logging$DefaultLogger started
[DEBUG] [12/29/2015 17:27:30.604] [run-main-0] [EventStream(akka://ConnectionManagement)] Default Loggers started
[DEBUG] [12/29/2015 17:27:36.442] [ConnectionManagement-akka.actor.default-dispatcher-4] [akka://ConnectionManagement/system/IO-TCP/selectors/$a/0] Successfully bound to /0:0:0:0:0:0:0:0:8888
[DEBUG] [12/29/2015 17:28:36.254] [ConnectionManagement-akka.actor.default-dispatcher-3] [akka://ConnectionManagement/system/IO-TCP/selectors/$a/0] New connection accepted
[DEBUG] [12/29/2015 17:28:37.194] [ConnectionManagement-akka.actor.default-dispatcher-6] [akka.actor.ActorSystemImpl(ConnectionManagement)] CM API: Response for
Request : HttpRequest(HttpMethod(GET),http://127.0.0.1:8888/ws/v1,List(UpgradeToWebsocket: , Host: 127.0.0.1:8888, Connection: Upgrade, Pragma: no-cache, Cache-Control: no-cache, Upgrade: websocket, Origin: http://localhost:3000, Sec-WebSocket-Version: 13, User-Agent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_2) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/47.0.2526.106 Safari/537.36, Accept-Encoding: gzip, deflate, sdch, Accept-Language: en-US, en;q=0.8, Sec-WebSocket-Key: SUGY/1jg/cOkUgwX5k7fVQ==, Sec-WebSocket-Extensions: permessage-deflate; client_max_window_bits),HttpEntity.Strict(none/none,ByteString()),HttpProtocol(HTTP/1.1))
Response: Complete(HttpResponse(101 Switching Protocols,List(Access-Control-Allow-Origin: *, Access-Control-Allow-Methods: GET, POST, PUT, OPTIONS, DELETE, Access-Control-Allow-Headers: Origin, X-Requested-With, Content-Type, Accept, Authorization, Upgrade: websocket, Connection: upgrade, Sec-WebSocket-Accept: uF+xxKpnLshZygyBmSNaImfbsmQ=, UpgradeToWebsocketResponseHeader: ),HttpEntity.Strict(none/none,ByteString()),HttpProtocol(HTTP/1.1)))
[DEBUG] [12/29/2015 17:28:37.441] [ConnectionManagement-akka.actor.default-dispatcher-14] [akka.stream.Log(akka://ConnectionManagement/user/$a)] [WS-REQUEST-IN] Element: Message entered requestSink
[DEBUG] [12/29/2015 17:28:37.702] [ConnectionManagement-akka.actor.default-dispatcher-17] [akka.stream.Log(akka://ConnectionManagement/user/$a)] [WS-REQUEST-DECODED] Element: CoapEnvelope(CoapHeader(Confirmable,OK,MessageId(620572340753,1),Some(Token(5072652930224448037)),CoapOptions(List(SerializationTypeOption(JsonSerializationType), PayloadTypeOption(ConnectDeviceApplicationRequestType)))),ByteVector(425 bytes, 0x7b2273657373696f6e4964223a2234704b436f45744939584e5a7345765a4a78584345595a55222c226465766963654170706c69636174696f6e4964223a22363230353732333430373533222c226465766963654170706c69636174696f6e496e666f223a7b22646576696365496e666f223a7b226f73496e666f223a7b226e616d65223a224d6163204f53222c2276657273696f6e223a2231302e31312e32227d2c2272756e74696d65496e666f223a7b226e616d65223a224368726f6d65222c2276657273696f6e223a2234372e302e323532362e313036227d2c226861726477617265496e666f223a7b2276656e646f72223a22536f6d65486172647761726556656e646f72222c226d6f64656c223a22536f6d6548617264776172654d6f64656c222c2276657273696f6e223a22536f6d65486172647761726556657273696f6e227d2c226e616d65223a22536f6d65446576696365227d2c22617070496e666f223a7b226f72674e616d65223a22496d70726976617461222c226170704e616d65223a22436f7274657874222c2261707056657273696f6e223a22342e302e30227d7d7d))
[ERROR] [12/29/2015 17:28:37.713] [ConnectionManagement-akka.actor.default-dispatcher-14] [akka.actor.ActorSystemImpl(ConnectionManagement)] Stream is failing: applying stop supervision strategy - reason: com.imprivata.cortext.server.connection.handler.WebSocketAdapter$MissingAuthenticationToken$
[ERROR] [12/29/2015 17:28:37.717] [ConnectionManagement-akka.actor.default-dispatcher-9] [akka.stream.Log(akka://ConnectionManagement/user/$a)] [WS-REQUEST-VALIDATED] Upstream failed.
com.imprivata.cortext.server.connection.handler.WebSocketAdapter$MissingAuthenticationToken$
at com.imprivata.cortext.server.connection.handler.WebSocketAdapter$MissingAuthenticationToken$.<clinit>(WebSocketAdapter.scala)
at com.imprivata.cortext.server.connection.handler.WebSocketAdapter$.com$imprivata$cortext$server$connection$handler$WebSocketAdapter$$validate(WebSocketAdapter.scala:225)
at com.imprivata.cortext.server.connection.handler.WebSocketAdapter$WebSocketAdapterImpl$$anonfun$7.apply(WebSocketAdapter.scala:171)
at com.imprivata.cortext.server.connection.handler.WebSocketAdapter$WebSocketAdapterImpl$$anonfun$7.apply(WebSocketAdapter.scala:171)
at akka.stream.impl.fusing.Map.onPush(Ops.scala:24)
at akka.stream.impl.fusing.Map.onPush(Ops.scala:23)
at akka.stream.impl.fusing.OneBoundedInterpreter$$anon$1.run(Interpreter.scala:436)
at akka.stream.impl.fusing.OneBoundedInterpreter$State$class.progress(Interpreter.scala:245)
at akka.stream.impl.fusing.OneBoundedInterpreter$$anon$1.progress(Interpreter.scala:434)
at akka.stream.impl.fusing.OneBoundedInterpreter.akka$stream$impl$fusing$OneBoundedInterpreter$$execute(Interpreter.scala:580)
at akka.stream.impl.fusing.OneBoundedInterpreter$State$class.execute(Interpreter.scala:241)
at akka.stream.impl.fusing.OneBoundedInterpreter$EntryState.execute(Interpreter.scala:666)
at akka.stream.stage.AbstractStage.enterAndPush(Stage.scala:66)
at akka.stream.impl.fusing.BatchingActorInputBoundary$$anonfun$upstreamRunning$1.applyOrElse(ActorInterpreter.scala:157)
at scala.runtime.AbstractPartialFunction.apply(AbstractPartialFunction.scala:36)
at akka.stream.impl.SubReceive.apply(Transfer.scala:16)
at akka.stream.impl.SubReceive.apply(Transfer.scala:12)
at scala.PartialFunction$class.applyOrElse(PartialFunction.scala:123)
at akka.stream.impl.SubReceive.applyOrElse(Transfer.scala:12)
at scala.PartialFunction$OrElse.applyOrElse(PartialFunction.scala:170)
at akka.actor.Actor$class.aroundReceive(Actor.scala:475)
at akka.stream.impl.fusing.ActorInterpreter.aroundReceive(ActorInterpreter.scala:366)
at akka.actor.ActorCell.receiveMessage(ActorCell.scala:516)
at akka.actor.ActorCell.invoke(ActorCell.scala:487)
at akka.dispatch.Mailbox.processMailbox(Mailbox.scala:256)
at akka.dispatch.Mailbox.run(Mailbox.scala:223)
at akka.dispatch.Mailbox.exec(Mailbox.scala:233)
at scala.concurrent.forkjoin.ForkJoinTask.doExec(ForkJoinTask.java:260)
at scala.concurrent.forkjoin.ForkJoinPool$WorkQueue.runTask(ForkJoinPool.java:1339)
at scala.concurrent.forkjoin.ForkJoinPool.runWorker(ForkJoinPool.java:1979)
at scala.concurrent.forkjoin.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:107)
[DEBUG] [12/29/2015 17:28:37.717] [ConnectionManagement-akka.actor.default-dispatcher-17] [akka.stream.Log(akka://ConnectionManagement/user/$a)] [WS-REQUEST-DECODED] Downstream finished.
[ERROR] [12/29/2015 17:28:37.717] [ConnectionManagement-akka.actor.default-dispatcher-9] [akka.stream.Log(akka://ConnectionManagement/user/$a)] [WS-REQUEST-PROCESSING-STATE] Upstream failed.
com.imprivata.cortext.server.connection.handler.WebSocketAdapter$MissingAuthenticationToken$
at com.imprivata.cortext.server.connection.handler.WebSocketAdapter$MissingAuthenticationToken$.<clinit>(WebSocketAdapter.scala)
at com.imprivata.cortext.server.connection.handler.WebSocketAdapter$.com$imprivata$cortext$server$connection$handler$WebSocketAdapter$$validate(WebSocketAdapter.scala:225)
at com.imprivata.cortext.server.connection.handler.WebSocketAdapter$WebSocketAdapterImpl$$anonfun$7.apply(WebSocketAdapter.scala:171)
at com.imprivata.cortext.server.connection.handler.WebSocketAdapter$WebSocketAdapterImpl$$anonfun$7.apply(WebSocketAdapter.scala:171)
at akka.stream.impl.fusing.Map.onPush(Ops.scala:24)
at akka.stream.impl.fusing.Map.onPush(Ops.scala:23)
at akka.stream.impl.fusing.OneBoundedInterpreter$$anon$1.run(Interpreter.scala:436)
at akka.stream.impl.fusing.OneBoundedInterpreter$State$class.progress(Interpreter.scala:245)
at akka.stream.impl.fusing.OneBoundedInterpreter$$anon$1.progress(Interpreter.scala:434)
at akka.stream.impl.fusing.OneBoundedInterpreter.akka$stream$impl$fusing$OneBoundedInterpreter$$execute(Interpreter.scala:580)
at akka.stream.impl.fusing.OneBoundedInterpreter$State$class.execute(Interpreter.scala:241)
at akka.stream.impl.fusing.OneBoundedInterpreter$EntryState.execute(Interpreter.scala:666)
at akka.stream.stage.AbstractStage.enterAndPush(Stage.scala:66)
at akka.stream.impl.fusing.BatchingActorInputBoundary$$anonfun$upstreamRunning$1.applyOrElse(ActorInterpreter.scala:157)
at scala.runtime.AbstractPartialFunction.apply(AbstractPartialFunction.scala:36)
at akka.stream.impl.SubReceive.apply(Transfer.scala:16)
at akka.stream.impl.SubReceive.apply(Transfer.scala:12)
at scala.PartialFunction$class.applyOrElse(PartialFunction.scala:123)
at akka.stream.impl.SubReceive.applyOrElse(Transfer.scala:12)
at scala.PartialFunction$OrElse.applyOrElse(PartialFunction.scala:170)
at akka.actor.Actor$class.aroundReceive(Actor.scala:475)
at akka.stream.impl.fusing.ActorInterpreter.aroundReceive(ActorInterpreter.scala:366)
at akka.actor.ActorCell.receiveMessage(ActorCell.scala:516)
at akka.actor.ActorCell.invoke(ActorCell.scala:487)
at akka.dispatch.Mailbox.processMailbox(Mailbox.scala:256)
at akka.dispatch.Mailbox.run(Mailbox.scala:223)
at akka.dispatch.Mailbox.exec(Mailbox.scala:233)
at scala.concurrent.forkjoin.ForkJoinTask.doExec(ForkJoinTask.java:260)
at scala.concurrent.forkjoin.ForkJoinPool$WorkQueue.runTask(ForkJoinPool.java:1339)
at scala.concurrent.forkjoin.ForkJoinPool.runWorker(ForkJoinPool.java:1979)
at scala.concurrent.forkjoin.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:107)
[DEBUG] [12/29/2015 17:28:37.717] [ConnectionManagement-akka.actor.default-dispatcher-13] [akka.stream.Log(akka://ConnectionManagement/user/$a)] [WS-REQUEST-IN] Downstream finished.
[ERROR] [12/29/2015 17:28:37.717] [ConnectionManagement-akka.actor.default-dispatcher-3] [akka.stream.Log(akka://ConnectionManagement/user/$a)] [WS-REQUEST-OUT] Upstream failed.
com.imprivata.cortext.server.connection.handler.WebSocketAdapter$MissingAuthenticationToken$
at com.imprivata.cortext.server.connection.handler.WebSocketAdapter$MissingAuthenticationToken$.<clinit>(WebSocketAdapter.scala)
at com.imprivata.cortext.server.connection.handler.WebSocketAdapter$.com$imprivata$cortext$server$connection$handler$WebSocketAdapter$$validate(WebSocketAdapter.scala:225)
at com.imprivata.cortext.server.connection.handler.WebSocketAdapter$WebSocketAdapterImpl$$anonfun$7.apply(WebSocketAdapter.scala:171)
at com.imprivata.cortext.server.connection.handler.WebSocketAdapter$WebSocketAdapterImpl$$anonfun$7.apply(WebSocketAdapter.scala:171)
at akka.stream.impl.fusing.Map.onPush(Ops.scala:24)
at akka.stream.impl.fusing.Map.onPush(Ops.scala:23)
at akka.stream.impl.fusing.OneBoundedInterpreter$$anon$1.run(Interpreter.scala:436)
at akka.stream.impl.fusing.OneBoundedInterpreter$State$class.progress(Interpreter.scala:245)
at akka.stream.impl.fusing.OneBoundedInterpreter$$anon$1.progress(Interpreter.scala:434)
at akka.stream.impl.fusing.OneBoundedInterpreter.akka$stream$impl$fusing$OneBoundedInterpreter$$execute(Interpreter.scala:580)
at akka.stream.impl.fusing.OneBoundedInterpreter$State$class.execute(Interpreter.scala:241)
at akka.stream.impl.fusing.OneBoundedInterpreter$EntryState.execute(Interpreter.scala:666)
at akka.stream.stage.AbstractStage.enterAndPush(Stage.scala:66)
at akka.stream.impl.fusing.BatchingActorInputBoundary$$anonfun$upstreamRunning$1.applyOrElse(ActorInterpreter.scala:157)
at scala.runtime.AbstractPartialFunction.apply(AbstractPartialFunction.scala:36)
at akka.stream.impl.SubReceive.apply(Transfer.scala:16)
at akka.stream.impl.SubReceive.apply(Transfer.scala:12)
at scala.PartialFunction$class.applyOrElse(PartialFunction.scala:123)
at akka.stream.impl.SubReceive.applyOrElse(Transfer.scala:12)
at scala.PartialFunction$OrElse.applyOrElse(PartialFunction.scala:170)
at akka.actor.Actor$class.aroundReceive(Actor.scala:475)
at akka.stream.impl.fusing.ActorInterpreter.aroundReceive(ActorInterpreter.scala:366)
at akka.actor.ActorCell.receiveMessage(ActorCell.scala:516)
at akka.actor.ActorCell.invoke(ActorCell.scala:487)
at akka.dispatch.Mailbox.processMailbox(Mailbox.scala:256)
at akka.dispatch.Mailbox.run(Mailbox.scala:223)
at akka.dispatch.Mailbox.exec(Mailbox.scala:233)
at scala.concurrent.forkjoin.ForkJoinTask.doExec(ForkJoinTask.java:260)
at scala.concurrent.forkjoin.ForkJoinPool$WorkQueue.runTask(ForkJoinPool.java:1339)
at scala.concurrent.forkjoin.ForkJoinPool.runWorker(ForkJoinPool.java:1979)
at scala.concurrent.forkjoin.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:107)
[DEBUG] [12/29/2015 17:28:42.050] [ConnectionManagement-akka.actor.default-dispatcher-15] [akka://ConnectionManagement/user/$a/flow-3-2-publisherSource-prefixAndTail] Cancelling akka.stream.impl.MultiStreamOutputProcessor$SubstreamOutput@533d077e (after: 5000 ms)
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment