2017-12-11 17:55:10,460 617 INFO - Starting command runner
2017-12-11 17:55:10,463 620 INFO - About to run full-command on device '192.168.197.70' with source 'Set(/Users/ss-indeni/Desktop/ss_indeni/parsers/src/panw/panos/show-ntp.ind)'
2017-12-11 17:55:10,464 621 INFO - Credentials: Set(ApiKey(None,LUFRPT1TMVdBWjBsVGt2aVhYVHRMSnhtbDJyc3dFeFU9N0Z3UzV6OE13QVJlTHlqdFVGcWN3VitLQXlRNzBja3BaVmd3OWhtbzc5RT0=,))
2017-12-11 17:55:10,487 644 WARN - no ssh credentials found for device 192.168.197.70(192.168.197.70)
2017-12-11 17:55:10,504 661 INFO - Compiling '/Users/ss-indeni/Desktop/ss_indeni/parsers/src/panw/panos/show-ntp.ind'
2017-12-11 17:55:12,220 2377 DEBUG - Adding new Set(CollectorInterrogationCommand(CollectorInterrogationCommandMetadata(ssh-interrogation,,EmptyRequirement),List(CollectorInterrogationCommandStep(SshInterrogationOperation,indeni.collector.commands.hardcoded.HardcodedSshCommandsFactory$$anon$1@407100c7))), CollectorInterrogationCommand(CollectorInterrogationCommandMetadata(https-interrogation,,EmptyRequirement),List(CollectorInterrogationCommandStep(HttpInterrogationOperation(https),indeni.collector.commands.hardcoded.HardcodedHttpCommandsFactory$$anon$1@49aac706)))) commands to execute.
[INFO] [12/11/2017 17:55:12.219] [Command-Runner-akka.actor.default-dispatcher-3] [akka://Command-Runner/user/interrogator-192.168.197.70] Interrogation Start interrogating device 192.168.197.70(192.168.197.70) with 2 commands
2017-12-11 17:55:12,224 2381 DEBUG - Command CollectorInterrogationCommand(CollectorInterrogationCommandMetadata(ssh-interrogation,,EmptyRequirement),List(CollectorInterrogationCommandStep(SshInterrogationOperation,indeni.collector.commands.hardcoded.HardcodedSshCommandsFactory$$anon$1@407100c7))) status changed to running
2017-12-11 17:55:12,226 2383 DEBUG - Command CollectorInterrogationCommand(CollectorInterrogationCommandMetadata(https-interrogation,,EmptyRequirement),List(CollectorInterrogationCommandStep(HttpInterrogationOperation(https),indeni.collector.commands.hardcoded.HardcodedHttpCommandsFactory$$anon$1@49aac706))) status changed to running
[INFO] [12/11/2017 17:55:12.226] [Command-Runner-akka.actor.default-dispatcher-3] [akka://Command-Runner/user/interrogator-192.168.197.70] Interrogation sending command to worker ExecuteCommand(CollectorInterrogationCommand(CollectorInterrogationCommandMetadata(ssh-interrogation,,EmptyRequirement),List(CollectorInterrogationCommandStep(SshInterrogationOperation,indeni.collector.commands.hardcoded.HardcodedSshCommandsFactory$$anon$1@407100c7))),DeviceInfo(192.168.197.70,192.168.197.70,192.168.197.70,Map(HTTPS_Port -> IntDeviceParameter(HTTPS_Port,443,Https)),Map()))
[INFO] [12/11/2017 17:55:12.226] [Command-Runner-akka.actor.default-dispatcher-3] [akka://Command-Runner/user/interrogator-192.168.197.70] Interrogation sending command to worker ExecuteCommand(CollectorInterrogationCommand(CollectorInterrogationCommandMetadata(https-interrogation,,EmptyRequirement),List(CollectorInterrogationCommandStep(HttpInterrogationOperation(https),indeni.collector.commands.hardcoded.HardcodedHttpCommandsFactory$$anon$1@49aac706))),DeviceInfo(192.168.197.70,192.168.197.70,192.168.197.70,Map(HTTPS_Port -> IntDeviceParameter(HTTPS_Port,443,Https)),Map()))
2017-12-11 17:55:13,949 4106 INFO - Trying to register BouncyCastle as a JCE provider
2017-12-11 17:55:14,321 4478 INFO - Registration succeeded
[INFO] [12/11/2017 17:55:14.599] [Command-Runner-akka.actor.default-dispatcher-2] [akka://Command-Runner/user/interrogator-192.168.197.70/CommandActor-192.168.197.70-192.168.197.70--7c3284b5-4a34-44c4-8ca8-325aa07fab54] Executor on receive execute command ssh-interrogation with operation Some(OperationRequest(SshInterrogationOperation,ssh-interrogation,Map(),Map()))
[INFO] [12/11/2017 17:55:14.599] [Command-Runner-akka.actor.default-dispatcher-6] [akka://Command-Runner/user/interrogator-192.168.197.70/CommandActor-192.168.197.70-192.168.197.70--823a10f6-af0e-4947-91cd-1200d93e6cf3] Executor on receive execute command https-interrogation with operation Some(OperationRequest(HttpInterrogationOperation(https),https-interrogation,Map(),Map()))
[WARN] [12/11/2017 17:55:14.603] [Command-Runner-akka.actor.default-dispatcher-2] [akka://Command-Runner/user/interrogator-192.168.197.70/CommandActor-192.168.197.70-192.168.197.70--7c3284b5-4a34-44c4-8ca8-325aa07fab54] Executor No appropriate client found for operation SshInterrogationOperation, command: ssh-interrogation did not execute
[INFO] [12/11/2017 17:55:14.606] [Command-Runner-akka.actor.default-dispatcher-8] [akka://Command-Runner/user/https-actor-17e0ec9d-938c-48d1-97ea-719a28155643-6bf21101-eb1b-4fa4-9ec6-658295ec3648] Received operation request from HandlerFromCommand-https-interrogation-1
2017-12-11 17:55:14,613 4770 DEBUG - un-monitoring command CollectorInterrogationCommand(CollectorInterrogationCommandMetadata(ssh-interrogation,,EmptyRequirement),List(CollectorInterrogationCommandStep(SshInterrogationOperation,indeni.collector.commands.hardcoded.HardcodedSshCommandsFactory$$anon$1@407100c7)))
[INFO] [12/11/2017 17:55:14.614] [Command-Runner-akka.actor.default-dispatcher-3] [akka://Command-Runner/user/interrogator-192.168.197.70] Interrogation command completed ssh-interrogation. waiting for:1 , success:0, failed:0, remaining: 0
[WARN] [12/11/2017 17:55:14.614] [Command-Runner-akka.actor.default-dispatcher-3] [akka://Command-Runner/user/interrogator-192.168.197.70] Got Failure result on device: 192.168.197.70(192.168.197.70) command: ssh-interrogation, indeni.collector.CommandFailure: Header = Command execution error,
Description = Command 'ssh-interrogation' failed to execute,
Message = Header = Execution Error,
Description = Executor No appropriate client found for operation SshInterrogationOperation, command: ssh-interrogation did not execute,
,
[INFO] [12/11/2017 17:55:14.614] [Command-Runner-akka.actor.default-dispatcher-3] [akka://Command-Runner/user/interrogator-192.168.197.70] Interrogation command: None
[INFO] [12/11/2017 17:55:14.723] [Command-Runner-akka.actor.default-dispatcher-2] [akka://Command-Runner/user/https-actor-17e0ec9d-938c-48d1-97ea-719a28155643-6bf21101-eb1b-4fa4-9ec6-658295ec3648] sending http request HandlerFromCommand-https-interrogation-1
[INFO] [12/11/2017 17:55:15.376] [Command-Runner-akka.actor.default-dispatcher-2] [akka://Command-Runner/user/interrogator-192.168.197.70/CommandActor-192.168.197.70-192.168.197.70--823a10f6-af0e-4947-91cd-1200d93e6cf3/HandlerFromCommand-https-interrogation-1] Command:= 'https-interrogation' ran as interrogation for device 192.168.197.70(192.168.197.70), returned Tags:= Map(https -> true).
[INFO] [12/11/2017 17:55:15.378] [Command-Runner-akka.actor.default-dispatcher-2] [akka://Command-Runner/user/interrogator-192.168.197.70/CommandActor-192.168.197.70-192.168.197.70--823a10f6-af0e-4947-91cd-1200d93e6cf3/HandlerFromCommand-https-interrogation-1] Executor results indeni.collector.actors.messages.CollectionCommandSuccess
2017-12-11 17:55:15,389 5546 INFO - {"v":"1","e":"137V5d4IoYYNht6VVmcE3gAAAQAga9gvOzatHTRcdrqEmUDYuowNDXGa9o0RxLDJEi2gIvk3ablUaxvUOemac4FGg2UJkIeb9sQZa4FpJPdoEkEfWuZ5Hhho7mgcWcVxz1/Zb7rWoyqCw937QpMH7FhQxKRB4HEU4KMIu5HMZ/65bbRlQSMYQn+uZDy99FjlgL7KEQSNphweZ//DHnrZ5aehCZqTLo0WBT6CNCpgyMt/63wI+9+tXOsrlahBQ7Rl3DewxpWIhWmsZT+wMiFGmG2Z+zeQN4dr3HKny48eGH6vi4o4vp9BHmLdc61K5awPBh4H5MbqF8zEVuSXdCrUc0QJ855kpUVUAhx4OE6OZn0WtQ8ygDJQluyMBaPiTPe98VFLGMWgpg27wMJSdnBWXlpZ701NV/K0Ev21W0W0wLeAD+DF0l5se78SNxS+vMHVbtDSkHxHhBbzy+hOp964CgVsemOvLVv+dQ+bgJ9hPrvGGvELmglzjPMEkuDug0iAUYd290Y4FH98LbUzMHBUv6ruzMRKg3kLELO715LO+LZbp4ovBO6a5LNS50oJtgxYQrrEUA=="}
2017-12-11 17:55:15,391 5548 DEBUG - un-monitoring command CollectorInterrogationCommand(CollectorInterrogationCommandMetadata(https-interrogation,,EmptyRequirement),List(CollectorInterrogationCommandStep(HttpInterrogationOperation(https),indeni.collector.commands.hardcoded.HardcodedHttpCommandsFactory$$anon$1@49aac706)))
[INFO] [12/11/2017 17:55:15.392] [Command-Runner-akka.actor.default-dispatcher-2] [akka://Command-Runner/user/interrogator-192.168.197.70] Interrogation command completed https-interrogation. waiting for:0 , success:0, failed:1, remaining: 0
2017-12-11 17:55:15,392 5549 DEBUG - Adding new Set() commands to execute.
[INFO] [12/11/2017 17:55:15.393] [Command-Runner-akka.actor.default-dispatcher-2] [akka://Command-Runner/user/interrogator-192.168.197.70] Interrogation success for device 192.168.197.70(192.168.197.70)
[INFO] [12/11/2017 17:55:15.393] [Command-Runner-akka.actor.default-dispatcher-2] [akka://Command-Runner/user/interrogator-192.168.197.70] Interrogation Tags found for device 192.168.197.70(192.168.197.70): https -> true
[INFO] [12/11/2017 17:55:15.393] [Command-Runner-akka.actor.default-dispatcher-2] [akka://Command-Runner/user/interrogator-192.168.197.70] Interrogation SUCCESS ! for device: 192.168.197.70(192.168.197.70)
2017-12-11 17:55:15,397 5554 INFO - Running 'panos-show-ntp'
[INFO] [12/11/2017 17:55:15.400] [Command-Runner-akka.actor.default-dispatcher-2] [akka://Command-Runner/user/CommandActor-Device:192.168.197.70-Command:panos-show-ntp] Executor on receive execute command panos-show-ntp with operation Some(OperationRequest(HttpOperation(GET '/api?type=op&cmd=&key=${api-key}', headers: Map(), body: None),panos-show-ntp,Map(),Map()))
[INFO] [12/11/2017 17:55:15.404] [Command-Runner-akka.actor.default-dispatcher-2] [akka://Command-Runner/user/https-actor-17e0ec9d-938c-48d1-97ea-719a28155643-6bf21101-eb1b-4fa4-9ec6-658295ec3648/https-executor-192.168.197.70-98fc5838-7b85-4918-b72e-f61abb3ffcc7] Got request from HandlerFromCommand-panos-show-ntp-1
[WARN] [12/11/2017 17:55:15.408] [Command-Runner-akka.actor.default-dispatcher-8] [akka://Command-Runner/user/https-actor-17e0ec9d-938c-48d1-97ea-719a28155643-6bf21101-eb1b-4fa4-9ec6-658295ec3648/https-executor-192.168.197.70-98fc5838-7b85-4918-b72e-f61abb3ffcc7] Fail to execute with credentials from HandlerFromCommand-panos-show-ntp-1, command: panos-show-ntp, operation: HttpOperation(GET '/api?type=op&cmd=&key=${api-key}', headers: Map(), body: None), vars: Map(), tags:Map()
2017-12-11 17:55:15,411 5568 ERROR - Command execution failure
indeni.collector.CommandFailure: Header = Command execution error,
Description = Command 'panos-show-ntp' failed to execute,
Message = Header = Authentication error,
Description = An error occurred during authentication,
Message = No such variable 'api-key' in '/api?type=op&cmd=&key=${api-key}',
,
at indeni.collector.actors.ResultsHandler$$anonfun$waitingForResults$1.applyOrElse(ResultsHandler.scala:74)
at akka.actor.Actor$class.aroundReceive(Actor.scala:497)
at indeni.collector.actors.ResultsHandler.aroundReceive(ResultsHandler.scala:24)
at akka.actor.ActorCell.receiveMessage(ActorCell.scala:526)
at akka.actor.ActorCell.invoke(ActorCell.scala:495)
at akka.dispatch.Mailbox.processMailbox(Mailbox.scala:257)
at akka.dispatch.Mailbox.run(Mailbox.scala:224)
at akka.dispatch.Mailbox.exec(Mailbox.scala:234)
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)
Caused by: indeni.collector.AuthenticationFailure: Header = Authentication error,
Description = An error occurred during authentication,
Message = No such variable 'api-key' in '/api?type=op&cmd=&key=${api-key}',
at indeni.collector.actors.executors.HttpExecutor$$anonfun$execute$1$$anonfun$applyOrElse$1.apply(HttpExecutor.scala:71)
at indeni.collector.actors.executors.HttpExecutor$$anonfun$execute$1$$anonfun$applyOrElse$1.apply(HttpExecutor.scala:66)
at scala.util.Success.foreach(Try.scala:236)
at scala.concurrent.Future$$anonfun$foreach$1.apply(Future.scala:206)
at scala.concurrent.Future$$anonfun$foreach$1.apply(Future.scala:206)
at scala.concurrent.impl.CallbackRunnable.run(Promise.scala:32)
at akka.dispatch.BatchingExecutor$AbstractBatch.processBatch(BatchingExecutor.scala:55)
at akka.dispatch.BatchingExecutor$BlockableBatch$$anonfun$run$1.apply$mcV$sp(BatchingExecutor.scala:91)
at akka.dispatch.BatchingExecutor$BlockableBatch$$anonfun$run$1.apply(BatchingExecutor.scala:91)
at akka.dispatch.BatchingExecutor$BlockableBatch$$anonfun$run$1.apply(BatchingExecutor.scala:91)
at scala.concurrent.BlockContext$.withBlockContext(BlockContext.scala:72)
at akka.dispatch.BatchingExecutor$BlockableBatch.run(BatchingExecutor.scala:90)
at akka.dispatch.TaskInvocation.run(AbstractDispatcher.scala:39)
at akka.dispatch.ForkJoinExecutorConfigurator$AkkaForkJoinTask.exec(AbstractDispatcher.scala:415)
... 4 common frames omitted
Caused by: java.lang.IllegalArgumentException: No such variable 'api-key' in '/api?type=op&cmd=&key=${api-key}'
at com.indeni.StringHelper$$anonfun$format$1.applyOrElse(StringHelper.scala:46)
at com.indeni.StringHelper$$anonfun$format$1.applyOrElse(StringHelper.scala:45)
at scala.runtime.AbstractPartialFunction.apply(AbstractPartialFunction.scala:36)
at scala.util.Failure$$anonfun$recover$1.apply(Try.scala:216)
at scala.util.Try$.apply(Try.scala:192)
at scala.util.Failure.recover(Try.scala:216)
at com.indeni.StringHelper$.format(StringHelper.scala:45)
at indeni.collector.actors.executors.HttpExecutor.indeni$collector$actors$executors$HttpExecutor$$resolveHttpRequest(HttpExecutor.scala:195)
at indeni.collector.actors.executors.HttpExecutor$$anonfun$indeni$collector$actors$executors$HttpExecutor$$createRequestsByCredentials$1$$anonfun$1.apply(HttpExecutor.scala:145)
at indeni.collector.actors.executors.HttpExecutor$$anonfun$indeni$collector$actors$executors$HttpExecutor$$createRequestsByCredentials$1$$anonfun$1.apply(HttpExecutor.scala:142)
at scala.collection.immutable.List.map(List.scala:273)
at indeni.collector.actors.executors.HttpExecutor$$anonfun$indeni$collector$actors$executors$HttpExecutor$$createRequestsByCredentials$1.apply(HttpExecutor.scala:142)
at indeni.collector.actors.executors.HttpExecutor$$anonfun$indeni$collector$actors$executors$HttpExecutor$$createRequestsByCredentials$1.apply(HttpExecutor.scala:140)
at scala.util.Success$$anonfun$map$1.apply(Try.scala:237)
at scala.util.Try$.apply(Try.scala:192)
at scala.util.Success.map(Try.scala:237)
at scala.concurrent.Future$$anonfun$map$1.apply(Future.scala:237)
at scala.concurrent.Future$$anonfun$map$1.apply(Future.scala:237)
... 13 common frames omitted
2017-12-11 17:55:15,411 5568 INFO - Exiting
[ERROR] [12/11/2017 17:55:15.421] [Command-Runner-akka.actor.default-dispatcher-7] [akka://Command-Runner/user/pool-master] connection pool for PoolGateway(hcps = HostConnectionPoolSetup(192.168.197.70,443,ConnectionPoolSetup(ConnectionPoolSettings(4,0,5,32,1,30 seconds,ClientConnectionSettings(Some(User-Agent: akka-http/10.0.6),10 seconds,1 minute,512,None,,List(),ParserSettings(2048,16,64,64,8192,64,8388608,256,1048576,Strict,RFC6265,true,Full,Error,Map(If-Range -> 0, If-Modified-Since -> 0, If-Unmodified-Since -> 0, default -> 12, Content-MD5 -> 0, Date -> 0, If-Match -> 0, If-None-Match -> 0, User-Agent -> 32),false,,,)),TCPTransport(None,ClientConnectionSettings(Some(User-Agent: akka-http/10.0.6),10 seconds,1 minute,512,None,,List(),ParserSettings(2048,16,64,64,8192,64,8388608,256,1048576,Strict,RFC6265,true,Full,Error,Map(If-Range -> 0, If-Modified-Since -> 0, If-Unmodified-Since -> 0, default -> 12, Content-MD5 -> 0, Date -> 0, If-Match -> 0, If-None-Match -> 0, User-Agent -> 32),false,,,)))),akka.http.scaladsl.HttpsConnectionContext@33314095,akka.event.MarkerLoggingAdapter@3362e0d6))) has shut down unexpectedly
[ERROR] [12/11/2017 17:55:15.426] [Command-Runner-akka.actor.default-dispatcher-4] [akka.actor.ActorSystemImpl(Command-Runner)] Outgoing request stream error (akka.stream.AbruptTerminationException)