Skip to content

Instantly share code, notes, and snippets.

@JoshRosen
Created August 6, 2014 20:14
Show Gist options
  • Save JoshRosen/41aeeafddbc4c1770d7b to your computer and use it in GitHub Desktop.
Save JoshRosen/41aeeafddbc4c1770d7b to your computer and use it in GitHub Desktop.
14/08/06 13:07:00.529 INFO SecurityManager: Changing view acls to: joshrosen
14/08/06 13:07:00.530 INFO SecurityManager: SecurityManager: authentication disabled; ui acls disabled; users with view permissions: Set(joshrosen)
14/08/06 13:07:00.981 INFO ConnectionManager: Bound socket to port 58887 with id = ConnectionManagerId(joshs-mbp,58887)
14/08/06 13:07:01.080 DEBUG ConnectionManager: Before Sending [BufferMessage(id = 2, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58887)] connectionid: joshs-mbp_58887_1
14/08/06 13:07:01.081 DEBUG ConnectionManager: Sending [BufferMessage(id = 2, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58887)]
14/08/06 13:07:01.081 DEBUG SendingConnection: Added [BufferMessage(id = 2, size = 10485760)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58887)]
14/08/06 13:07:01.082 DEBUG ConnectionManager: Selector selected 0 of 1 keys
14/08/06 13:07:01.084 INFO SendingConnection: Initiating connection to [joshs-mbp/192.168.1.245:58887]
14/08/06 13:07:01.087 INFO ConnectionManager: Accepted connection from [joshs-mbp/192.168.1.245]
14/08/06 13:07:01.089 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.089 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.089 INFO SendingConnection: Connected to [joshs-mbp/192.168.1.245:58887], 1 messages pending
14/08/06 13:07:01.090 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.091 DEBUG SendingConnection: Starting to send [BufferMessage(id = 2, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58887)]
14/08/06 13:07:01.095 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.096 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.101 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 2, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58887)]
14/08/06 13:07:01.102 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.102 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.103 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.104 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.104 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.106 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.106 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.106 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.106 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.107 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.107 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.107 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.107 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.108 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.108 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.109 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.109 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.109 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.110 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.110 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.110 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.110 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.111 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.111 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.111 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.111 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.112 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.112 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.112 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.113 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.113 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.113 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.114 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.114 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.114 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.115 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.115 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.115 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.115 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.116 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.116 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.116 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.116 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.117 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.117 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.117 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.117 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.118 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 2, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58887)] in 17 ms
14/08/06 13:07:01.118 DEBUG ConnectionManager: Received [BufferMessage(id = 2, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58887)]
14/08/06 13:07:01.118 DEBUG SendingConnection: Finished sending [BufferMessage(id = 2, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58887)] in 27 ms
14/08/06 13:07:01.119 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.119 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:07:01.119 DEBUG ConnectionManager: Handling [BufferMessage(id = 2, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58887)]
14/08/06 13:07:01.120 DEBUG ConnectionManager: Calling back
14/08/06 13:07:01.120 DEBUG ConnectionManager: Before Sending [BufferAckMessage(aid = 2, id = 3, size = 0)] to [ConnectionManagerId(joshs-mbp,58887)] connectionid: joshs-mbp_58887_1
14/08/06 13:07:01.120 DEBUG ConnectionManager: Sending [BufferAckMessage(aid = 2, id = 3, size = 0)] to [ConnectionManagerId(joshs-mbp,58887)]
14/08/06 13:07:01.120 DEBUG SendingConnection: Added [BufferAckMessage(aid = 2, id = 3, size = 0)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58887)]
14/08/06 13:07:01.120 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.120 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.121 DEBUG ConnectionManager: Handling delay is 1 ms
14/08/06 13:07:01.121 DEBUG SendingConnection: Starting to send [BufferAckMessage(aid = 2, id = 3, size = 0)] to [ConnectionManagerId(joshs-mbp,58887)]
14/08/06 13:07:01.121 DEBUG SendingConnection: Finished sending [BufferAckMessage(aid = 2, id = 3, size = 0)] to [ConnectionManagerId(joshs-mbp,58887)] in 0 ms
14/08/06 13:07:01.121 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.121 DEBUG ConnectionManager: Received [BufferAckMessage(aid = 2, id = 3, size = 0)] from [ConnectionManagerId(joshs-mbp,58887)]
14/08/06 13:07:01.121 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.121 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:07:01.121 DEBUG ConnectionManager: Handling [BufferAckMessage(aid = 2, id = 3, size = 0)] from [ConnectionManagerId(joshs-mbp,58887)]
14/08/06 13:07:01.123 DEBUG ConnectionManager: Handling delay is 2 ms
14/08/06 13:07:01.123 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.123 INFO ConnectionManager: Selector thread was interrupted!
14/08/06 13:07:01.125 INFO ConnectionManager: Removing SendingConnection to ConnectionManagerId(joshs-mbp,58887)
14/08/06 13:07:01.127 INFO ConnectionManager: Removing ReceivingConnection to ConnectionManagerId(joshs-mbp,58887)
14/08/06 13:07:01.127 ERROR ConnectionManager: Corresponding SendingConnectionManagerId not found
14/08/06 13:07:01.127 WARN ConnectionManager: All connections not cleaned up
14/08/06 13:07:01.128 INFO ConnectionManager: ConnectionManager stopped
14/08/06 13:07:01.134 INFO SecurityManager: Changing view acls to: joshrosen
14/08/06 13:07:01.149 DEBUG Configuration: java.io.IOException: config()
at org.apache.hadoop.conf.Configuration.<init>(Configuration.java:227)
at org.apache.hadoop.conf.Configuration.<init>(Configuration.java:214)
at org.apache.spark.deploy.SparkHadoopUtil.newConfiguration(SparkHadoopUtil.scala:71)
at org.apache.spark.deploy.SparkHadoopUtil.<init>(SparkHadoopUtil.scala:35)
at org.apache.spark.deploy.SparkHadoopUtil$.<init>(SparkHadoopUtil.scala:109)
at org.apache.spark.deploy.SparkHadoopUtil$.<clinit>(SparkHadoopUtil.scala)
at org.apache.spark.SecurityManager.generateSecretKey(SecurityManager.scala:198)
at org.apache.spark.SecurityManager.<init>(SecurityManager.scala:148)
at org.apache.spark.network.ConnectionManagerSuite$$anonfun$2.apply$mcV$sp(ConnectionManagerSuite.scala:61)
at org.apache.spark.network.ConnectionManagerSuite$$anonfun$2.apply(ConnectionManagerSuite.scala:57)
at org.apache.spark.network.ConnectionManagerSuite$$anonfun$2.apply(ConnectionManagerSuite.scala:57)
at org.scalatest.Transformer$$anonfun$apply$1.apply(Transformer.scala:22)
at org.scalatest.Transformer$$anonfun$apply$1.apply(Transformer.scala:22)
at org.scalatest.OutcomeOf$class.outcomeOf(OutcomeOf.scala:85)
at org.scalatest.OutcomeOf$.outcomeOf(OutcomeOf.scala:104)
at org.scalatest.Transformer.apply(Transformer.scala:22)
at org.scalatest.Transformer.apply(Transformer.scala:20)
at org.scalatest.FunSuiteLike$$anon$1.apply(FunSuiteLike.scala:158)
at org.scalatest.Suite$class.withFixture(Suite.scala:1121)
at org.scalatest.FunSuite.withFixture(FunSuite.scala:1559)
at org.scalatest.FunSuiteLike$class.invokeWithFixture$1(FunSuiteLike.scala:155)
at org.scalatest.FunSuiteLike$$anonfun$runTest$1.apply(FunSuiteLike.scala:167)
at org.scalatest.FunSuiteLike$$anonfun$runTest$1.apply(FunSuiteLike.scala:167)
at org.scalatest.SuperEngine.runTestImpl(Engine.scala:306)
at org.scalatest.FunSuiteLike$class.runTest(FunSuiteLike.scala:167)
at org.scalatest.FunSuite.runTest(FunSuite.scala:1559)
at org.scalatest.FunSuiteLike$$anonfun$runTests$1.apply(FunSuiteLike.scala:200)
at org.scalatest.FunSuiteLike$$anonfun$runTests$1.apply(FunSuiteLike.scala:200)
at org.scalatest.SuperEngine$$anonfun$traverseSubNodes$1$1.apply(Engine.scala:413)
at org.scalatest.SuperEngine$$anonfun$traverseSubNodes$1$1.apply(Engine.scala:401)
at scala.collection.immutable.List.foreach(List.scala:318)
at org.scalatest.SuperEngine.traverseSubNodes$1(Engine.scala:401)
at org.scalatest.SuperEngine.org$scalatest$SuperEngine$$runTestsInBranch(Engine.scala:396)
at org.scalatest.SuperEngine.runTestsImpl(Engine.scala:483)
at org.scalatest.FunSuiteLike$class.runTests(FunSuiteLike.scala:200)
at org.scalatest.FunSuite.runTests(FunSuite.scala:1559)
at org.scalatest.Suite$class.run(Suite.scala:1423)
at org.scalatest.FunSuite.org$scalatest$FunSuiteLike$$super$run(FunSuite.scala:1559)
at org.scalatest.FunSuiteLike$$anonfun$run$1.apply(FunSuiteLike.scala:204)
at org.scalatest.FunSuiteLike$$anonfun$run$1.apply(FunSuiteLike.scala:204)
at org.scalatest.SuperEngine.runImpl(Engine.scala:545)
at org.scalatest.FunSuiteLike$class.run(FunSuiteLike.scala:204)
at org.scalatest.FunSuite.run(FunSuite.scala:1559)
at org.scalatest.tools.Framework.org$scalatest$tools$Framework$$runSuite(Framework.scala:444)
at org.scalatest.tools.Framework$ScalaTestTask.execute(Framework.scala:651)
at sbt.ForkMain$Run$2.call(ForkMain.java:294)
at sbt.ForkMain$Run$2.call(ForkMain.java:284)
at java.util.concurrent.FutureTask.run(FutureTask.java:262)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
14/08/06 13:07:01.180 DEBUG Groups: Creating new Groups object
14/08/06 13:07:01.212 DEBUG Groups: Group mapping impl=org.apache.hadoop.security.ShellBasedUnixGroupsMapping; cacheTimeout=300000
14/08/06 13:07:01.219 DEBUG Configuration: java.io.IOException: config()
at org.apache.hadoop.conf.Configuration.<init>(Configuration.java:227)
at org.apache.hadoop.conf.Configuration.<init>(Configuration.java:214)
at org.apache.hadoop.security.UserGroupInformation.ensureInitialized(UserGroupInformation.java:184)
at org.apache.hadoop.security.UserGroupInformation.isSecurityEnabled(UserGroupInformation.java:236)
at org.apache.hadoop.security.KerberosName.<clinit>(KerberosName.java:79)
at org.apache.hadoop.security.UserGroupInformation.initialize(UserGroupInformation.java:209)
at org.apache.hadoop.security.UserGroupInformation.setConfiguration(UserGroupInformation.java:226)
at org.apache.spark.deploy.SparkHadoopUtil.<init>(SparkHadoopUtil.scala:36)
at org.apache.spark.deploy.SparkHadoopUtil$.<init>(SparkHadoopUtil.scala:109)
at org.apache.spark.deploy.SparkHadoopUtil$.<clinit>(SparkHadoopUtil.scala)
at org.apache.spark.SecurityManager.generateSecretKey(SecurityManager.scala:198)
at org.apache.spark.SecurityManager.<init>(SecurityManager.scala:148)
at org.apache.spark.network.ConnectionManagerSuite$$anonfun$2.apply$mcV$sp(ConnectionManagerSuite.scala:61)
at org.apache.spark.network.ConnectionManagerSuite$$anonfun$2.apply(ConnectionManagerSuite.scala:57)
at org.apache.spark.network.ConnectionManagerSuite$$anonfun$2.apply(ConnectionManagerSuite.scala:57)
at org.scalatest.Transformer$$anonfun$apply$1.apply(Transformer.scala:22)
at org.scalatest.Transformer$$anonfun$apply$1.apply(Transformer.scala:22)
at org.scalatest.OutcomeOf$class.outcomeOf(OutcomeOf.scala:85)
at org.scalatest.OutcomeOf$.outcomeOf(OutcomeOf.scala:104)
at org.scalatest.Transformer.apply(Transformer.scala:22)
at org.scalatest.Transformer.apply(Transformer.scala:20)
at org.scalatest.FunSuiteLike$$anon$1.apply(FunSuiteLike.scala:158)
at org.scalatest.Suite$class.withFixture(Suite.scala:1121)
at org.scalatest.FunSuite.withFixture(FunSuite.scala:1559)
at org.scalatest.FunSuiteLike$class.invokeWithFixture$1(FunSuiteLike.scala:155)
at org.scalatest.FunSuiteLike$$anonfun$runTest$1.apply(FunSuiteLike.scala:167)
at org.scalatest.FunSuiteLike$$anonfun$runTest$1.apply(FunSuiteLike.scala:167)
at org.scalatest.SuperEngine.runTestImpl(Engine.scala:306)
at org.scalatest.FunSuiteLike$class.runTest(FunSuiteLike.scala:167)
at org.scalatest.FunSuite.runTest(FunSuite.scala:1559)
at org.scalatest.FunSuiteLike$$anonfun$runTests$1.apply(FunSuiteLike.scala:200)
at org.scalatest.FunSuiteLike$$anonfun$runTests$1.apply(FunSuiteLike.scala:200)
at org.scalatest.SuperEngine$$anonfun$traverseSubNodes$1$1.apply(Engine.scala:413)
at org.scalatest.SuperEngine$$anonfun$traverseSubNodes$1$1.apply(Engine.scala:401)
at scala.collection.immutable.List.foreach(List.scala:318)
at org.scalatest.SuperEngine.traverseSubNodes$1(Engine.scala:401)
at org.scalatest.SuperEngine.org$scalatest$SuperEngine$$runTestsInBranch(Engine.scala:396)
at org.scalatest.SuperEngine.runTestsImpl(Engine.scala:483)
at org.scalatest.FunSuiteLike$class.runTests(FunSuiteLike.scala:200)
at org.scalatest.FunSuite.runTests(FunSuite.scala:1559)
at org.scalatest.Suite$class.run(Suite.scala:1423)
at org.scalatest.FunSuite.org$scalatest$FunSuiteLike$$super$run(FunSuite.scala:1559)
at org.scalatest.FunSuiteLike$$anonfun$run$1.apply(FunSuiteLike.scala:204)
at org.scalatest.FunSuiteLike$$anonfun$run$1.apply(FunSuiteLike.scala:204)
at org.scalatest.SuperEngine.runImpl(Engine.scala:545)
at org.scalatest.FunSuiteLike$class.run(FunSuiteLike.scala:204)
at org.scalatest.FunSuite.run(FunSuite.scala:1559)
at org.scalatest.tools.Framework.org$scalatest$tools$Framework$$runSuite(Framework.scala:444)
at org.scalatest.tools.Framework$ScalaTestTask.execute(Framework.scala:651)
at sbt.ForkMain$Run$2.call(ForkMain.java:294)
at sbt.ForkMain$Run$2.call(ForkMain.java:284)
at java.util.concurrent.FutureTask.run(FutureTask.java:262)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
14/08/06 13:07:01.258 INFO SecurityManager: SecurityManager: authentication enabled; ui acls disabled; users with view permissions: Set(joshrosen)
14/08/06 13:07:01.259 INFO ConnectionManager: Bound socket to port 58889 with id = ConnectionManagerId(joshs-mbp,58889)
14/08/06 13:07:01.260 INFO ConnectionManager: Bound socket to port 58890 with id = ConnectionManagerId(joshs-mbp,58890)
14/08/06 13:07:01.386 DEBUG SecurityMessage: message total size is : 42
14/08/06 13:07:01.387 DEBUG SendingConnection: Added [BufferMessage(id = 5, size = 42)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58889)]
14/08/06 13:07:01.387 DEBUG ConnectionManager: Selector selected 0 of 1 keys
14/08/06 13:07:01.387 INFO SendingConnection: Initiating connection to [joshs-mbp/192.168.1.245:58889]
14/08/06 13:07:01.387 DEBUG ConnectionManager: adding connectionsAwaitingSasl id: joshs-mbp_58889_1
14/08/06 13:07:01.387 DEBUG ConnectionManager: Before Sending [BufferMessage(id = 4, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58889)] connectionid: joshs-mbp_58889_1
14/08/06 13:07:01.388 INFO SendingConnection: Connected to [joshs-mbp/192.168.1.245:58889], 1 messages pending
14/08/06 13:07:01.388 INFO ConnectionManager: Accepted connection from [joshs-mbp/192.168.1.245]
14/08/06 13:07:01.388 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58889_1
14/08/06 13:07:01.388 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.388 DEBUG SendingConnection: Starting to send [BufferMessage(id = 5, size = 42)] to [ConnectionManagerId(joshs-mbp,58889)]
14/08/06 13:07:01.389 DEBUG SendingConnection: Finished sending [BufferMessage(id = 5, size = 42)] to [ConnectionManagerId(joshs-mbp,58889)] in 0 ms
14/08/06 13:07:01.389 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.389 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 5, size = 42)] from [ConnectionManagerId(joshs-mbp,58889)]
14/08/06 13:07:01.389 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 5, size = 42)] from [ConnectionManagerId(joshs-mbp,58889)] in 0 ms
14/08/06 13:07:01.390 DEBUG ConnectionManager: Received [BufferMessage(id = 5, size = 42)] from [ConnectionManagerId(joshs-mbp,58889)]
14/08/06 13:07:01.390 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:07:01.390 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:07:01.390 DEBUG ConnectionManager: Handling [BufferMessage(id = 5, size = 42)] from [ConnectionManagerId(joshs-mbp,58889)]
14/08/06 13:07:01.390 DEBUG ConnectionManager: This is security neg message
14/08/06 13:07:01.391 DEBUG ConnectionManager: Client handleAuth for id: joshs-mbp_58889_1
14/08/06 13:07:01.392 ERROR ConnectionManager: Error handling sasl client authentication
javax.security.sasl.SaslException: DIGEST-MD5: Digest-challenge format violation: algorithm directive missing
at com.sun.security.sasl.digest.DigestMD5Client.processChallenge(DigestMD5Client.java:296)
at com.sun.security.sasl.digest.DigestMD5Client.evaluateChallenge(DigestMD5Client.java:225)
at org.apache.spark.SparkSaslClient.saslResponse(SparkSaslClient.scala:84)
at org.apache.spark.network.ConnectionManager.handleClientAuthentication(ConnectionManager.scala:533)
at org.apache.spark.network.ConnectionManager.handleAuthentication(ConnectionManager.scala:610)
at org.apache.spark.network.ConnectionManager.org$apache$spark$network$ConnectionManager$$handleMessage(ConnectionManager.scala:639)
at org.apache.spark.network.ConnectionManager$$anon$9.run(ConnectionManager.scala:511)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
14/08/06 13:07:01.393 INFO ConnectionManager: Removing SendingConnection to ConnectionManagerId(joshs-mbp,58889)
14/08/06 13:07:01.393 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:07:01.393 INFO ConnectionManager: Removing ReceivingConnection to ConnectionManagerId(joshs-mbp,58889)
14/08/06 13:07:01.393 INFO ConnectionManager: Notifying org.apache.spark.network.ConnectionManager$MessageStatus@1792a0c1
14/08/06 13:07:01.393 ERROR ConnectionManager: Corresponding SendingConnectionManagerId not found
14/08/06 13:07:01.394 DEBUG ConnectionManager: Selector selected 0 of 1 keys
14/08/06 13:07:01.394 DEBUG ConnectionManager: Selector selected 0 of 1 keys
14/08/06 13:07:01.889 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58889_1
14/08/06 13:07:02.390 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58889_1
14/08/06 13:07:02.891 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58889_1
14/08/06 13:07:03.392 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58889_1
14/08/06 13:07:03.892 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58889_1
14/08/06 13:07:04.393 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58889_1
14/08/06 13:07:04.893 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58889_1
14/08/06 13:07:05.394 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58889_1
14/08/06 13:07:05.895 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58889_1
14/08/06 13:07:06.396 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58889_1
14/08/06 13:07:06.897 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58889_1
14/08/06 13:07:07.398 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58889_1
14/08/06 13:07:07.899 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58889_1
14/08/06 13:07:08.400 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58889_1
14/08/06 13:07:08.901 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58889_1
14/08/06 13:07:09.403 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58889_1
14/08/06 13:09:37.778 INFO SecurityManager: Changing view acls to: joshrosen
14/08/06 13:09:37.779 INFO SecurityManager: SecurityManager: authentication disabled; ui acls disabled; users with view permissions: Set(joshrosen)
14/08/06 13:09:37.825 INFO ConnectionManager: Bound socket to port 58929 with id = ConnectionManagerId(joshs-mbp,58929)
14/08/06 13:09:37.931 DEBUG ConnectionManager: Before Sending [BufferMessage(id = 2, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58929)] connectionid: joshs-mbp_58929_1
14/08/06 13:09:37.931 DEBUG ConnectionManager: Sending [BufferMessage(id = 2, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58929)]
14/08/06 13:09:37.931 DEBUG SendingConnection: Added [BufferMessage(id = 2, size = 10485760)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58929)]
14/08/06 13:09:37.932 DEBUG ConnectionManager: Selector selected 0 of 1 keys
14/08/06 13:09:37.934 INFO SendingConnection: Initiating connection to [joshs-mbp/192.168.1.245:58929]
14/08/06 13:09:37.937 INFO SendingConnection: Connected to [joshs-mbp/192.168.1.245:58929], 1 messages pending
14/08/06 13:09:37.938 INFO ConnectionManager: Accepted connection from [joshs-mbp/192.168.1.245]
14/08/06 13:09:37.940 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.941 DEBUG SendingConnection: Starting to send [BufferMessage(id = 2, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58929)]
14/08/06 13:09:37.945 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.946 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.950 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 2, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58929)]
14/08/06 13:09:37.952 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.952 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.953 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.954 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.955 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.955 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.956 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.957 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.958 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.958 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.959 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.960 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.961 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.961 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.962 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.962 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.963 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.963 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.964 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.965 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.966 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.966 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.967 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.967 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.968 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 2, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58929)] in 18 ms
14/08/06 13:09:37.968 DEBUG SendingConnection: Finished sending [BufferMessage(id = 2, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58929)] in 27 ms
14/08/06 13:09:37.969 DEBUG ConnectionManager: Received [BufferMessage(id = 2, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58929)]
14/08/06 13:09:37.969 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.970 DEBUG ConnectionManager: Handler thread delay is 1 ms
14/08/06 13:09:37.970 DEBUG ConnectionManager: Handling [BufferMessage(id = 2, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58929)]
14/08/06 13:09:37.970 DEBUG ConnectionManager: Calling back
14/08/06 13:09:37.971 DEBUG ConnectionManager: Before Sending [BufferAckMessage(aid = 2, id = 3, size = 0)] to [ConnectionManagerId(joshs-mbp,58929)] connectionid: joshs-mbp_58929_1
14/08/06 13:09:37.971 DEBUG ConnectionManager: Sending [BufferAckMessage(aid = 2, id = 3, size = 0)] to [ConnectionManagerId(joshs-mbp,58929)]
14/08/06 13:09:37.971 DEBUG SendingConnection: Added [BufferAckMessage(aid = 2, id = 3, size = 0)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58929)]
14/08/06 13:09:37.971 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.971 DEBUG ConnectionManager: Handling delay is 2 ms
14/08/06 13:09:37.971 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.971 DEBUG SendingConnection: Starting to send [BufferAckMessage(aid = 2, id = 3, size = 0)] to [ConnectionManagerId(joshs-mbp,58929)]
14/08/06 13:09:37.971 DEBUG SendingConnection: Finished sending [BufferAckMessage(aid = 2, id = 3, size = 0)] to [ConnectionManagerId(joshs-mbp,58929)] in 0 ms
14/08/06 13:09:37.972 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.972 DEBUG ConnectionManager: Received [BufferAckMessage(aid = 2, id = 3, size = 0)] from [ConnectionManagerId(joshs-mbp,58929)]
14/08/06 13:09:37.972 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.972 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:09:37.972 DEBUG ConnectionManager: Handling [BufferAckMessage(aid = 2, id = 3, size = 0)] from [ConnectionManagerId(joshs-mbp,58929)]
14/08/06 13:09:37.974 DEBUG ConnectionManager: Handling delay is 2 ms
14/08/06 13:09:37.974 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:37.974 INFO ConnectionManager: Selector thread was interrupted!
14/08/06 13:09:37.976 INFO ConnectionManager: Removing SendingConnection to ConnectionManagerId(joshs-mbp,58929)
14/08/06 13:09:37.978 INFO ConnectionManager: Removing ReceivingConnection to ConnectionManagerId(joshs-mbp,58929)
14/08/06 13:09:37.978 ERROR ConnectionManager: Corresponding SendingConnectionManagerId not found
14/08/06 13:09:37.979 WARN ConnectionManager: All connections not cleaned up
14/08/06 13:09:37.979 INFO ConnectionManager: ConnectionManager stopped
14/08/06 13:09:37.989 INFO SecurityManager: Changing view acls to: joshrosen
14/08/06 13:09:38.006 DEBUG Configuration: java.io.IOException: config()
at org.apache.hadoop.conf.Configuration.<init>(Configuration.java:227)
at org.apache.hadoop.conf.Configuration.<init>(Configuration.java:214)
at org.apache.spark.deploy.SparkHadoopUtil.newConfiguration(SparkHadoopUtil.scala:71)
at org.apache.spark.deploy.SparkHadoopUtil.<init>(SparkHadoopUtil.scala:35)
at org.apache.spark.deploy.SparkHadoopUtil$.<init>(SparkHadoopUtil.scala:109)
at org.apache.spark.deploy.SparkHadoopUtil$.<clinit>(SparkHadoopUtil.scala)
at org.apache.spark.SecurityManager.generateSecretKey(SecurityManager.scala:198)
at org.apache.spark.SecurityManager.<init>(SecurityManager.scala:148)
at org.apache.spark.network.ConnectionManagerSuite$$anonfun$2.apply$mcV$sp(ConnectionManagerSuite.scala:60)
at org.apache.spark.network.ConnectionManagerSuite$$anonfun$2.apply(ConnectionManagerSuite.scala:56)
at org.apache.spark.network.ConnectionManagerSuite$$anonfun$2.apply(ConnectionManagerSuite.scala:56)
at org.scalatest.Transformer$$anonfun$apply$1.apply(Transformer.scala:22)
at org.scalatest.Transformer$$anonfun$apply$1.apply(Transformer.scala:22)
at org.scalatest.OutcomeOf$class.outcomeOf(OutcomeOf.scala:85)
at org.scalatest.OutcomeOf$.outcomeOf(OutcomeOf.scala:104)
at org.scalatest.Transformer.apply(Transformer.scala:22)
at org.scalatest.Transformer.apply(Transformer.scala:20)
at org.scalatest.FunSuiteLike$$anon$1.apply(FunSuiteLike.scala:158)
at org.scalatest.Suite$class.withFixture(Suite.scala:1121)
at org.scalatest.FunSuite.withFixture(FunSuite.scala:1559)
at org.scalatest.FunSuiteLike$class.invokeWithFixture$1(FunSuiteLike.scala:155)
at org.scalatest.FunSuiteLike$$anonfun$runTest$1.apply(FunSuiteLike.scala:167)
at org.scalatest.FunSuiteLike$$anonfun$runTest$1.apply(FunSuiteLike.scala:167)
at org.scalatest.SuperEngine.runTestImpl(Engine.scala:306)
at org.scalatest.FunSuiteLike$class.runTest(FunSuiteLike.scala:167)
at org.scalatest.FunSuite.runTest(FunSuite.scala:1559)
at org.scalatest.FunSuiteLike$$anonfun$runTests$1.apply(FunSuiteLike.scala:200)
at org.scalatest.FunSuiteLike$$anonfun$runTests$1.apply(FunSuiteLike.scala:200)
at org.scalatest.SuperEngine$$anonfun$traverseSubNodes$1$1.apply(Engine.scala:413)
at org.scalatest.SuperEngine$$anonfun$traverseSubNodes$1$1.apply(Engine.scala:401)
at scala.collection.immutable.List.foreach(List.scala:318)
at org.scalatest.SuperEngine.traverseSubNodes$1(Engine.scala:401)
at org.scalatest.SuperEngine.org$scalatest$SuperEngine$$runTestsInBranch(Engine.scala:396)
at org.scalatest.SuperEngine.runTestsImpl(Engine.scala:483)
at org.scalatest.FunSuiteLike$class.runTests(FunSuiteLike.scala:200)
at org.scalatest.FunSuite.runTests(FunSuite.scala:1559)
at org.scalatest.Suite$class.run(Suite.scala:1423)
at org.scalatest.FunSuite.org$scalatest$FunSuiteLike$$super$run(FunSuite.scala:1559)
at org.scalatest.FunSuiteLike$$anonfun$run$1.apply(FunSuiteLike.scala:204)
at org.scalatest.FunSuiteLike$$anonfun$run$1.apply(FunSuiteLike.scala:204)
at org.scalatest.SuperEngine.runImpl(Engine.scala:545)
at org.scalatest.FunSuiteLike$class.run(FunSuiteLike.scala:204)
at org.scalatest.FunSuite.run(FunSuite.scala:1559)
at org.scalatest.tools.Framework.org$scalatest$tools$Framework$$runSuite(Framework.scala:444)
at org.scalatest.tools.Framework$ScalaTestTask.execute(Framework.scala:651)
at sbt.ForkMain$Run$2.call(ForkMain.java:294)
at sbt.ForkMain$Run$2.call(ForkMain.java:284)
at java.util.concurrent.FutureTask.run(FutureTask.java:262)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
14/08/06 13:09:38.052 DEBUG Groups: Creating new Groups object
14/08/06 13:09:38.093 DEBUG Groups: Group mapping impl=org.apache.hadoop.security.ShellBasedUnixGroupsMapping; cacheTimeout=300000
14/08/06 13:09:38.099 DEBUG Configuration: java.io.IOException: config()
at org.apache.hadoop.conf.Configuration.<init>(Configuration.java:227)
at org.apache.hadoop.conf.Configuration.<init>(Configuration.java:214)
at org.apache.hadoop.security.UserGroupInformation.ensureInitialized(UserGroupInformation.java:184)
at org.apache.hadoop.security.UserGroupInformation.isSecurityEnabled(UserGroupInformation.java:236)
at org.apache.hadoop.security.KerberosName.<clinit>(KerberosName.java:79)
at org.apache.hadoop.security.UserGroupInformation.initialize(UserGroupInformation.java:209)
at org.apache.hadoop.security.UserGroupInformation.setConfiguration(UserGroupInformation.java:226)
at org.apache.spark.deploy.SparkHadoopUtil.<init>(SparkHadoopUtil.scala:36)
at org.apache.spark.deploy.SparkHadoopUtil$.<init>(SparkHadoopUtil.scala:109)
at org.apache.spark.deploy.SparkHadoopUtil$.<clinit>(SparkHadoopUtil.scala)
at org.apache.spark.SecurityManager.generateSecretKey(SecurityManager.scala:198)
at org.apache.spark.SecurityManager.<init>(SecurityManager.scala:148)
at org.apache.spark.network.ConnectionManagerSuite$$anonfun$2.apply$mcV$sp(ConnectionManagerSuite.scala:60)
at org.apache.spark.network.ConnectionManagerSuite$$anonfun$2.apply(ConnectionManagerSuite.scala:56)
at org.apache.spark.network.ConnectionManagerSuite$$anonfun$2.apply(ConnectionManagerSuite.scala:56)
at org.scalatest.Transformer$$anonfun$apply$1.apply(Transformer.scala:22)
at org.scalatest.Transformer$$anonfun$apply$1.apply(Transformer.scala:22)
at org.scalatest.OutcomeOf$class.outcomeOf(OutcomeOf.scala:85)
at org.scalatest.OutcomeOf$.outcomeOf(OutcomeOf.scala:104)
at org.scalatest.Transformer.apply(Transformer.scala:22)
at org.scalatest.Transformer.apply(Transformer.scala:20)
at org.scalatest.FunSuiteLike$$anon$1.apply(FunSuiteLike.scala:158)
at org.scalatest.Suite$class.withFixture(Suite.scala:1121)
at org.scalatest.FunSuite.withFixture(FunSuite.scala:1559)
at org.scalatest.FunSuiteLike$class.invokeWithFixture$1(FunSuiteLike.scala:155)
at org.scalatest.FunSuiteLike$$anonfun$runTest$1.apply(FunSuiteLike.scala:167)
at org.scalatest.FunSuiteLike$$anonfun$runTest$1.apply(FunSuiteLike.scala:167)
at org.scalatest.SuperEngine.runTestImpl(Engine.scala:306)
at org.scalatest.FunSuiteLike$class.runTest(FunSuiteLike.scala:167)
at org.scalatest.FunSuite.runTest(FunSuite.scala:1559)
at org.scalatest.FunSuiteLike$$anonfun$runTests$1.apply(FunSuiteLike.scala:200)
at org.scalatest.FunSuiteLike$$anonfun$runTests$1.apply(FunSuiteLike.scala:200)
at org.scalatest.SuperEngine$$anonfun$traverseSubNodes$1$1.apply(Engine.scala:413)
at org.scalatest.SuperEngine$$anonfun$traverseSubNodes$1$1.apply(Engine.scala:401)
at scala.collection.immutable.List.foreach(List.scala:318)
at org.scalatest.SuperEngine.traverseSubNodes$1(Engine.scala:401)
at org.scalatest.SuperEngine.org$scalatest$SuperEngine$$runTestsInBranch(Engine.scala:396)
at org.scalatest.SuperEngine.runTestsImpl(Engine.scala:483)
at org.scalatest.FunSuiteLike$class.runTests(FunSuiteLike.scala:200)
at org.scalatest.FunSuite.runTests(FunSuite.scala:1559)
at org.scalatest.Suite$class.run(Suite.scala:1423)
at org.scalatest.FunSuite.org$scalatest$FunSuiteLike$$super$run(FunSuite.scala:1559)
at org.scalatest.FunSuiteLike$$anonfun$run$1.apply(FunSuiteLike.scala:204)
at org.scalatest.FunSuiteLike$$anonfun$run$1.apply(FunSuiteLike.scala:204)
at org.scalatest.SuperEngine.runImpl(Engine.scala:545)
at org.scalatest.FunSuiteLike$class.run(FunSuiteLike.scala:204)
at org.scalatest.FunSuite.run(FunSuite.scala:1559)
at org.scalatest.tools.Framework.org$scalatest$tools$Framework$$runSuite(Framework.scala:444)
at org.scalatest.tools.Framework$ScalaTestTask.execute(Framework.scala:651)
at sbt.ForkMain$Run$2.call(ForkMain.java:294)
at sbt.ForkMain$Run$2.call(ForkMain.java:284)
at java.util.concurrent.FutureTask.run(FutureTask.java:262)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
14/08/06 13:09:38.139 INFO SecurityManager: SecurityManager: authentication enabled; ui acls disabled; users with view permissions: Set(joshrosen)
14/08/06 13:09:38.139 INFO ConnectionManager: Bound socket to port 58931 with id = ConnectionManagerId(joshs-mbp,58931)
14/08/06 13:09:38.141 INFO ConnectionManager: Bound socket to port 58932 with id = ConnectionManagerId(joshs-mbp,58932)
14/08/06 13:09:38.266 DEBUG SecurityMessage: message total size is : 42
14/08/06 13:09:38.266 DEBUG SendingConnection: Added [BufferMessage(id = 5, size = 42)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.266 DEBUG ConnectionManager: Selector selected 0 of 1 keys
14/08/06 13:09:38.267 INFO SendingConnection: Initiating connection to [joshs-mbp/192.168.1.245:58932]
14/08/06 13:09:38.267 DEBUG ConnectionManager: adding connectionsAwaitingSasl id: joshs-mbp_58931_1
14/08/06 13:09:38.267 DEBUG ConnectionManager: Before Sending [BufferMessage(id = 4, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)] connectionid: joshs-mbp_58931_1
14/08/06 13:09:38.267 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:09:38.267 INFO SendingConnection: Connected to [joshs-mbp/192.168.1.245:58932], 1 messages pending
14/08/06 13:09:38.267 INFO ConnectionManager: Accepted connection from [joshs-mbp/192.168.1.245]
14/08/06 13:09:38.267 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:09:38.267 DEBUG SendingConnection: Starting to send [BufferMessage(id = 5, size = 42)] to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.267 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58931_1
14/08/06 13:09:38.268 DEBUG SendingConnection: Finished sending [BufferMessage(id = 5, size = 42)] to [ConnectionManagerId(joshs-mbp,58932)] in 1 ms
14/08/06 13:09:38.268 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:09:38.268 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 5, size = 42)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.268 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 5, size = 42)] from [ConnectionManagerId(joshs-mbp,58931)] in 0 ms
14/08/06 13:09:38.268 DEBUG ConnectionManager: Received [BufferMessage(id = 5, size = 42)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.269 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:09:38.269 DEBUG ConnectionManager: Handler thread delay is 1 ms
14/08/06 13:09:38.269 DEBUG ConnectionManager: Handling [BufferMessage(id = 5, size = 42)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.269 DEBUG ConnectionManager: This is security neg message
14/08/06 13:09:38.270 DEBUG ConnectionManager: Server handleAuth for id: joshs-mbp_58931_1
14/08/06 13:09:38.271 DEBUG ConnectionManager: saslContext not established
14/08/06 13:09:38.271 DEBUG ConnectionManager: Creating sasl Server
14/08/06 13:09:38.273 DEBUG ConnectionManager: Server sasl not completed: joshs-mbp_58932_1
14/08/06 13:09:38.273 DEBUG SecurityMessage: message total size is : 150
14/08/06 13:09:38.273 INFO ConnectionManager: creating new sending connection for security! joshs-mbp_58932_2
14/08/06 13:09:38.273 DEBUG SendingConnection: Added [BufferMessage(id = 6, size = 150)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.273 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:09:38.273 INFO SendingConnection: Initiating connection to [joshs-mbp/192.168.1.245:58931]
14/08/06 13:09:38.273 DEBUG ConnectionManager: After handleAuth result was true, returning
14/08/06 13:09:38.274 DEBUG ConnectionManager: Handling delay is 6 ms
14/08/06 13:09:38.274 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.274 INFO SendingConnection: Connected to [joshs-mbp/192.168.1.245:58931], 1 messages pending
14/08/06 13:09:38.274 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.274 INFO ConnectionManager: Accepted connection from [joshs-mbp/192.168.1.245]
14/08/06 13:09:38.274 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.274 DEBUG SendingConnection: Starting to send [BufferMessage(id = 6, size = 150)] to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.274 DEBUG SendingConnection: Finished sending [BufferMessage(id = 6, size = 150)] to [ConnectionManagerId(joshs-mbp,58931)] in 0 ms
14/08/06 13:09:38.275 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.275 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 6, size = 150)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.275 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 6, size = 150)] from [ConnectionManagerId(joshs-mbp,58932)] in 0 ms
14/08/06 13:09:38.275 DEBUG ConnectionManager: Received [BufferMessage(id = 6, size = 150)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.276 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.276 DEBUG ConnectionManager: Handler thread delay is 1 ms
14/08/06 13:09:38.276 DEBUG ConnectionManager: Handling [BufferMessage(id = 6, size = 150)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.276 DEBUG ConnectionManager: This is security neg message
14/08/06 13:09:38.276 DEBUG ConnectionManager: Client handleAuth for id: joshs-mbp_58931_1
14/08/06 13:09:38.277 DEBUG SparkSaslClient: in the sasl client callback handler
14/08/06 13:09:38.278 DEBUG SparkSaslClient: handle: SASL client callback: setting realm: default
14/08/06 13:09:38.278 DEBUG SparkSaslClient: handle: SASL client callback: setting username: c3BhcmtTYXNsVXNlcg==
14/08/06 13:09:38.278 DEBUG SparkSaslClient: handle: SASL client callback: setting userPassword
14/08/06 13:09:38.279 DEBUG SecurityMessage: message total size is : 304
14/08/06 13:09:38.279 DEBUG SendingConnection: Added [BufferMessage(id = 7, size = 304)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.279 DEBUG ConnectionManager: After handleAuth result was true, returning
14/08/06 13:09:38.279 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.279 DEBUG ConnectionManager: Handling delay is 4 ms
14/08/06 13:09:38.280 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.280 DEBUG SendingConnection: Starting to send [BufferMessage(id = 7, size = 304)] to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.280 DEBUG SendingConnection: Finished sending [BufferMessage(id = 7, size = 304)] to [ConnectionManagerId(joshs-mbp,58932)] in 0 ms
14/08/06 13:09:38.280 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.281 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 7, size = 304)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.281 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 7, size = 304)] from [ConnectionManagerId(joshs-mbp,58931)] in 0 ms
14/08/06 13:09:38.281 DEBUG ConnectionManager: Received [BufferMessage(id = 7, size = 304)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.281 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.281 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:09:38.281 DEBUG ConnectionManager: Handling [BufferMessage(id = 7, size = 304)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.281 DEBUG ConnectionManager: This is security neg message
14/08/06 13:09:38.282 DEBUG ConnectionManager: Server handleAuth for id: joshs-mbp_58931_1
14/08/06 13:09:38.282 DEBUG ConnectionManager: saslContext not established
14/08/06 13:09:38.282 DEBUG SparkSaslServer: In the sasl server callback handler
14/08/06 13:09:38.283 DEBUG SparkSaslServer: handle: SASL server callback: setting realm: default
14/08/06 13:09:38.283 DEBUG SparkSaslServer: handle: SASL server callback: setting username
14/08/06 13:09:38.283 DEBUG SparkSaslServer: handle: SASL server callback: setting userPassword
14/08/06 13:09:38.284 DEBUG SparkSaslServer: In the sasl server callback handler
14/08/06 13:09:38.284 DEBUG SparkSaslServer: set auth to true
14/08/06 13:09:38.284 DEBUG SparkSaslServer: sasl server is authorized
14/08/06 13:09:38.285 DEBUG ConnectionManager: Server sasl completed: joshs-mbp_58932_1
14/08/06 13:09:38.285 DEBUG SecurityMessage: message total size is : 82
14/08/06 13:09:38.285 DEBUG SendingConnection: Added [BufferMessage(id = 8, size = 82)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.285 DEBUG ConnectionManager: After handleAuth result was true, returning
14/08/06 13:09:38.285 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.285 DEBUG ConnectionManager: Handling delay is 4 ms
14/08/06 13:09:38.285 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.285 DEBUG SendingConnection: Starting to send [BufferMessage(id = 8, size = 82)] to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.286 DEBUG SendingConnection: Finished sending [BufferMessage(id = 8, size = 82)] to [ConnectionManagerId(joshs-mbp,58931)] in 1 ms
14/08/06 13:09:38.286 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.286 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 8, size = 82)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.286 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 8, size = 82)] from [ConnectionManagerId(joshs-mbp,58932)] in 0 ms
14/08/06 13:09:38.286 DEBUG ConnectionManager: Received [BufferMessage(id = 8, size = 82)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.287 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.287 DEBUG ConnectionManager: Handler thread delay is 1 ms
14/08/06 13:09:38.287 DEBUG ConnectionManager: Handling [BufferMessage(id = 8, size = 82)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.287 DEBUG ConnectionManager: This is security neg message
14/08/06 13:09:38.287 DEBUG ConnectionManager: Client handleAuth for id: joshs-mbp_58931_1
14/08/06 13:09:38.288 DEBUG ConnectionManager: Client sasl completed after evaluate for id: joshs-mbp_58931_1
14/08/06 13:09:38.288 DEBUG ConnectionManager: After handleAuth result was true, returning
14/08/06 13:09:38.288 DEBUG ConnectionManager: Handling delay is 2 ms
14/08/06 13:09:38.288 DEBUG ConnectionManager: Sending [BufferMessage(id = 4, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.288 DEBUG SendingConnection: Added [BufferMessage(id = 4, size = 10485760)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.288 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.288 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.288 DEBUG SendingConnection: Starting to send [BufferMessage(id = 4, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.288 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.289 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.290 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 4, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.290 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.291 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.291 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.291 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.292 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.292 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.292 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.293 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.293 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.294 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.294 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.294 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.294 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.295 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.295 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.295 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.296 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.296 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.296 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.296 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.296 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.297 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.297 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.297 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.297 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.298 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.298 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.298 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.298 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.298 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.298 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.299 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.299 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.299 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.299 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.299 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.300 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.300 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.300 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.300 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.300 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.301 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.301 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.301 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.301 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.302 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.302 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.302 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.302 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.302 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.303 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.303 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.303 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.303 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.303 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.304 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.304 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.304 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.304 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.305 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.305 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.305 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.305 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.305 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.306 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.306 DEBUG SendingConnection: Finished sending [BufferMessage(id = 4, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)] in 18 ms
14/08/06 13:09:38.306 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 4, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)] in 17 ms
14/08/06 13:09:38.306 DEBUG ConnectionManager: Received [BufferMessage(id = 4, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.306 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.306 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:09:38.306 DEBUG ConnectionManager: Handling [BufferMessage(id = 4, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.306 DEBUG ConnectionManager: Calling back
14/08/06 13:09:38.307 DEBUG SecurityMessage: message total size is : 42
14/08/06 13:09:38.307 DEBUG SendingConnection: Added [BufferMessage(id = 10, size = 42)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.307 DEBUG ConnectionManager: adding connectionsAwaitingSasl id: joshs-mbp_58932_2
14/08/06 13:09:38.307 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.307 DEBUG ConnectionManager: Before Sending [BufferAckMessage(aid = 4, id = 9, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)] connectionid: joshs-mbp_58932_2
14/08/06 13:09:38.307 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58932_2
14/08/06 13:09:38.308 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.308 DEBUG SendingConnection: Starting to send [BufferMessage(id = 10, size = 42)] to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.308 DEBUG SendingConnection: Finished sending [BufferMessage(id = 10, size = 42)] to [ConnectionManagerId(joshs-mbp,58931)] in 0 ms
14/08/06 13:09:38.308 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.308 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 10, size = 42)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.308 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 10, size = 42)] from [ConnectionManagerId(joshs-mbp,58932)] in 0 ms
14/08/06 13:09:38.308 DEBUG ConnectionManager: Received [BufferMessage(id = 10, size = 42)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.309 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.309 DEBUG ConnectionManager: Handler thread delay is 1 ms
14/08/06 13:09:38.309 DEBUG ConnectionManager: Handling [BufferMessage(id = 10, size = 42)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.309 DEBUG ConnectionManager: This is security neg message
14/08/06 13:09:38.309 DEBUG ConnectionManager: Server handleAuth for id: joshs-mbp_58932_2
14/08/06 13:09:38.309 DEBUG ConnectionManager: saslContext not established
14/08/06 13:09:38.309 DEBUG ConnectionManager: Creating sasl Server
14/08/06 13:09:38.310 DEBUG ConnectionManager: Server sasl not completed: joshs-mbp_58931_2
14/08/06 13:09:38.310 DEBUG SecurityMessage: message total size is : 150
14/08/06 13:09:38.310 DEBUG SendingConnection: Added [BufferMessage(id = 11, size = 150)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.310 DEBUG ConnectionManager: After handleAuth result was true, returning
14/08/06 13:09:38.310 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.310 DEBUG ConnectionManager: Handling delay is 2 ms
14/08/06 13:09:38.310 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.310 DEBUG SendingConnection: Starting to send [BufferMessage(id = 11, size = 150)] to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.310 DEBUG SendingConnection: Finished sending [BufferMessage(id = 11, size = 150)] to [ConnectionManagerId(joshs-mbp,58932)] in 0 ms
14/08/06 13:09:38.310 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.310 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 11, size = 150)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.310 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 11, size = 150)] from [ConnectionManagerId(joshs-mbp,58931)] in 0 ms
14/08/06 13:09:38.311 DEBUG ConnectionManager: Received [BufferMessage(id = 11, size = 150)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.311 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.311 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:09:38.311 DEBUG ConnectionManager: Handling [BufferMessage(id = 11, size = 150)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.311 DEBUG ConnectionManager: This is security neg message
14/08/06 13:09:38.311 DEBUG ConnectionManager: Client handleAuth for id: joshs-mbp_58932_2
14/08/06 13:09:38.312 DEBUG SparkSaslClient: in the sasl client callback handler
14/08/06 13:09:38.312 DEBUG SparkSaslClient: handle: SASL client callback: setting realm: default
14/08/06 13:09:38.312 DEBUG SparkSaslClient: handle: SASL client callback: setting username: c3BhcmtTYXNsVXNlcg==
14/08/06 13:09:38.312 DEBUG SparkSaslClient: handle: SASL client callback: setting userPassword
14/08/06 13:09:38.312 DEBUG SecurityMessage: message total size is : 304
14/08/06 13:09:38.312 DEBUG SendingConnection: Added [BufferMessage(id = 12, size = 304)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.312 DEBUG ConnectionManager: After handleAuth result was true, returning
14/08/06 13:09:38.312 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.312 DEBUG ConnectionManager: Handling delay is 1 ms
14/08/06 13:09:38.312 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.312 DEBUG SendingConnection: Starting to send [BufferMessage(id = 12, size = 304)] to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.313 DEBUG SendingConnection: Finished sending [BufferMessage(id = 12, size = 304)] to [ConnectionManagerId(joshs-mbp,58931)] in 0 ms
14/08/06 13:09:38.313 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.313 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 12, size = 304)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.313 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 12, size = 304)] from [ConnectionManagerId(joshs-mbp,58932)] in 0 ms
14/08/06 13:09:38.313 DEBUG ConnectionManager: Received [BufferMessage(id = 12, size = 304)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.313 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.313 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:09:38.313 DEBUG ConnectionManager: Handling [BufferMessage(id = 12, size = 304)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.314 DEBUG ConnectionManager: This is security neg message
14/08/06 13:09:38.314 DEBUG ConnectionManager: Server handleAuth for id: joshs-mbp_58932_2
14/08/06 13:09:38.314 DEBUG ConnectionManager: saslContext not established
14/08/06 13:09:38.314 DEBUG SparkSaslServer: In the sasl server callback handler
14/08/06 13:09:38.314 DEBUG SparkSaslServer: handle: SASL server callback: setting realm: default
14/08/06 13:09:38.314 DEBUG SparkSaslServer: handle: SASL server callback: setting username
14/08/06 13:09:38.314 DEBUG SparkSaslServer: handle: SASL server callback: setting userPassword
14/08/06 13:09:38.314 DEBUG SparkSaslServer: In the sasl server callback handler
14/08/06 13:09:38.314 DEBUG SparkSaslServer: set auth to true
14/08/06 13:09:38.314 DEBUG SparkSaslServer: sasl server is authorized
14/08/06 13:09:38.315 DEBUG ConnectionManager: Server sasl completed: joshs-mbp_58931_2
14/08/06 13:09:38.315 DEBUG SecurityMessage: message total size is : 82
14/08/06 13:09:38.315 DEBUG SendingConnection: Added [BufferMessage(id = 13, size = 82)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.315 DEBUG ConnectionManager: After handleAuth result was true, returning
14/08/06 13:09:38.315 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.315 DEBUG ConnectionManager: Handling delay is 2 ms
14/08/06 13:09:38.315 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.315 DEBUG SendingConnection: Starting to send [BufferMessage(id = 13, size = 82)] to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.315 DEBUG SendingConnection: Finished sending [BufferMessage(id = 13, size = 82)] to [ConnectionManagerId(joshs-mbp,58932)] in 0 ms
14/08/06 13:09:38.315 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.315 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 13, size = 82)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.316 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 13, size = 82)] from [ConnectionManagerId(joshs-mbp,58931)] in 1 ms
14/08/06 13:09:38.316 DEBUG ConnectionManager: Received [BufferMessage(id = 13, size = 82)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.316 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.316 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:09:38.316 DEBUG ConnectionManager: Handling [BufferMessage(id = 13, size = 82)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.316 DEBUG ConnectionManager: This is security neg message
14/08/06 13:09:38.316 DEBUG ConnectionManager: Client handleAuth for id: joshs-mbp_58932_2
14/08/06 13:09:38.317 DEBUG ConnectionManager: Client sasl completed after evaluate for id: joshs-mbp_58932_2
14/08/06 13:09:38.317 DEBUG ConnectionManager: After handleAuth result was true, returning
14/08/06 13:09:38.317 DEBUG ConnectionManager: Handling delay is 1 ms
14/08/06 13:09:38.317 DEBUG ConnectionManager: Sending [BufferAckMessage(aid = 4, id = 9, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.317 DEBUG SendingConnection: Added [BufferAckMessage(aid = 4, id = 9, size = 0)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.317 DEBUG ConnectionManager: Handling delay is 11 ms
14/08/06 13:09:38.317 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.317 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.317 DEBUG SendingConnection: Starting to send [BufferAckMessage(aid = 4, id = 9, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.317 DEBUG SendingConnection: Finished sending [BufferAckMessage(aid = 4, id = 9, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)] in 0 ms
14/08/06 13:09:38.317 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.318 DEBUG ConnectionManager: Received [BufferAckMessage(aid = 4, id = 9, size = 0)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.318 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.318 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:09:38.318 DEBUG ConnectionManager: Handling [BufferAckMessage(aid = 4, id = 9, size = 0)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.318 DEBUG ConnectionManager: Handling delay is 0 ms
14/08/06 13:09:38.319 DEBUG ConnectionManager: Sasl already established
14/08/06 13:09:38.319 DEBUG ConnectionManager: Before Sending [BufferMessage(id = 14, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)] connectionid: joshs-mbp_58931_1
14/08/06 13:09:38.319 DEBUG ConnectionManager: Sending [BufferMessage(id = 14, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.319 DEBUG SendingConnection: Added [BufferMessage(id = 14, size = 10485760)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.319 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.319 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.319 DEBUG SendingConnection: Starting to send [BufferMessage(id = 14, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.319 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.320 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 14, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.321 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.321 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.321 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.322 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.322 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.322 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.322 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.323 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.323 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.323 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.323 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.323 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.324 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.324 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.324 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.324 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.325 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.325 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.325 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.325 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.325 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.326 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.326 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.326 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.326 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.326 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.327 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.327 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.327 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.327 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.327 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.328 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.328 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.328 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.328 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.328 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.329 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.329 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.329 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.329 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.329 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.330 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.330 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.330 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.330 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.330 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.331 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.331 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.331 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.331 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.331 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.331 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.332 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.332 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.332 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.332 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.333 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.333 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.333 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.333 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.333 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.334 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.334 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.334 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.334 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.334 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.335 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.335 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.335 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.335 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.335 DEBUG SendingConnection: Finished sending [BufferMessage(id = 14, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)] in 16 ms
14/08/06 13:09:38.335 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 14, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)] in 15 ms
14/08/06 13:09:38.336 DEBUG ConnectionManager: Received [BufferMessage(id = 14, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.336 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.336 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:09:38.336 DEBUG ConnectionManager: Handling [BufferMessage(id = 14, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.336 DEBUG ConnectionManager: Calling back
14/08/06 13:09:38.336 DEBUG ConnectionManager: Sasl already established
14/08/06 13:09:38.336 DEBUG ConnectionManager: Before Sending [BufferAckMessage(aid = 14, id = 15, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)] connectionid: joshs-mbp_58932_2
14/08/06 13:09:38.336 DEBUG ConnectionManager: Sending [BufferAckMessage(aid = 14, id = 15, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.336 DEBUG SendingConnection: Added [BufferAckMessage(aid = 14, id = 15, size = 0)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.336 DEBUG ConnectionManager: Handling delay is 0 ms
14/08/06 13:09:38.336 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.337 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.337 DEBUG SendingConnection: Starting to send [BufferAckMessage(aid = 14, id = 15, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.337 DEBUG SendingConnection: Finished sending [BufferAckMessage(aid = 14, id = 15, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)] in 0 ms
14/08/06 13:09:38.337 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.337 DEBUG ConnectionManager: Received [BufferAckMessage(aid = 14, id = 15, size = 0)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.337 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.337 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:09:38.337 DEBUG ConnectionManager: Handling [BufferAckMessage(aid = 14, id = 15, size = 0)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.337 DEBUG ConnectionManager: Handling delay is 0 ms
14/08/06 13:09:38.338 DEBUG ConnectionManager: Sasl already established
14/08/06 13:09:38.338 DEBUG ConnectionManager: Before Sending [BufferMessage(id = 16, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)] connectionid: joshs-mbp_58931_1
14/08/06 13:09:38.338 DEBUG ConnectionManager: Sending [BufferMessage(id = 16, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.338 DEBUG SendingConnection: Added [BufferMessage(id = 16, size = 10485760)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.338 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.338 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.338 DEBUG SendingConnection: Starting to send [BufferMessage(id = 16, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.338 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.342 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 16, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.342 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.342 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.343 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.343 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.343 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.343 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.344 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.344 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.345 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.345 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.346 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.346 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.346 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.347 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.347 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.347 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.347 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.347 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.348 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.348 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.348 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.348 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.348 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.349 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.349 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.349 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.349 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.350 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.350 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.350 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.350 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.350 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.351 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.351 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.351 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.351 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.352 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.352 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.352 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.352 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.352 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.353 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.353 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.353 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.353 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.353 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.354 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.354 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.354 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.354 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.354 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.354 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.355 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.355 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.355 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.355 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.355 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.355 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.356 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.356 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.356 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.356 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.356 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.357 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.357 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.357 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.357 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.357 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.358 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.358 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.358 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.358 DEBUG SendingConnection: Finished sending [BufferMessage(id = 16, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)] in 20 ms
14/08/06 13:09:38.358 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.358 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 16, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)] in 16 ms
14/08/06 13:09:38.359 DEBUG ConnectionManager: Received [BufferMessage(id = 16, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.359 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.359 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:09:38.359 DEBUG ConnectionManager: Handling [BufferMessage(id = 16, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.359 DEBUG ConnectionManager: Calling back
14/08/06 13:09:38.359 DEBUG ConnectionManager: Sasl already established
14/08/06 13:09:38.359 DEBUG ConnectionManager: Before Sending [BufferAckMessage(aid = 16, id = 17, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)] connectionid: joshs-mbp_58932_2
14/08/06 13:09:38.359 DEBUG ConnectionManager: Sending [BufferAckMessage(aid = 16, id = 17, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.359 DEBUG SendingConnection: Added [BufferAckMessage(aid = 16, id = 17, size = 0)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.359 DEBUG ConnectionManager: Handling delay is 0 ms
14/08/06 13:09:38.359 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.360 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.360 DEBUG SendingConnection: Starting to send [BufferAckMessage(aid = 16, id = 17, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.360 DEBUG SendingConnection: Finished sending [BufferAckMessage(aid = 16, id = 17, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)] in 0 ms
14/08/06 13:09:38.360 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.360 DEBUG ConnectionManager: Received [BufferAckMessage(aid = 16, id = 17, size = 0)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.360 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.360 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:09:38.360 DEBUG ConnectionManager: Handling [BufferAckMessage(aid = 16, id = 17, size = 0)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.360 DEBUG ConnectionManager: Handling delay is 0 ms
14/08/06 13:09:38.361 DEBUG ConnectionManager: Sasl already established
14/08/06 13:09:38.361 DEBUG ConnectionManager: Before Sending [BufferMessage(id = 18, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)] connectionid: joshs-mbp_58931_1
14/08/06 13:09:38.361 DEBUG ConnectionManager: Sending [BufferMessage(id = 18, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.361 DEBUG SendingConnection: Added [BufferMessage(id = 18, size = 10485760)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.361 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.361 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.361 DEBUG SendingConnection: Starting to send [BufferMessage(id = 18, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.361 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.362 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.362 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 18, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.363 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.363 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.363 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.364 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.364 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.364 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.364 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.365 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.365 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.365 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.365 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.366 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.366 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.366 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.366 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.366 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.366 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.367 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.367 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.367 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.367 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.367 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.368 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.368 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.368 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.368 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.368 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.369 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.369 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.369 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.369 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.369 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.369 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.370 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.370 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.370 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.370 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.370 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.370 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.371 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.371 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.371 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.371 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.371 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.371 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.372 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.372 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.372 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.372 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.372 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.372 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.373 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.373 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.373 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.373 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.373 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.373 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.374 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.374 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.374 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.374 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.374 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.375 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.375 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.375 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.375 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.375 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.376 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.376 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.377 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.377 DEBUG SendingConnection: Finished sending [BufferMessage(id = 18, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)] in 16 ms
14/08/06 13:09:38.377 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.377 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 18, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)] in 15 ms
14/08/06 13:09:38.377 DEBUG ConnectionManager: Received [BufferMessage(id = 18, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.378 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.378 DEBUG ConnectionManager: Handler thread delay is 1 ms
14/08/06 13:09:38.378 DEBUG ConnectionManager: Handling [BufferMessage(id = 18, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.378 DEBUG ConnectionManager: Calling back
14/08/06 13:09:38.378 DEBUG ConnectionManager: Sasl already established
14/08/06 13:09:38.378 DEBUG ConnectionManager: Before Sending [BufferAckMessage(aid = 18, id = 19, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)] connectionid: joshs-mbp_58932_2
14/08/06 13:09:38.378 DEBUG ConnectionManager: Sending [BufferAckMessage(aid = 18, id = 19, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.378 DEBUG SendingConnection: Added [BufferAckMessage(aid = 18, id = 19, size = 0)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.378 DEBUG ConnectionManager: Handling delay is 1 ms
14/08/06 13:09:38.378 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.378 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.378 DEBUG SendingConnection: Starting to send [BufferAckMessage(aid = 18, id = 19, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.378 DEBUG SendingConnection: Finished sending [BufferAckMessage(aid = 18, id = 19, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)] in 0 ms
14/08/06 13:09:38.378 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.379 DEBUG ConnectionManager: Received [BufferAckMessage(aid = 18, id = 19, size = 0)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.379 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.379 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:09:38.379 DEBUG ConnectionManager: Handling [BufferAckMessage(aid = 18, id = 19, size = 0)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.379 DEBUG ConnectionManager: Handling delay is 0 ms
14/08/06 13:09:38.379 DEBUG ConnectionManager: Sasl already established
14/08/06 13:09:38.379 DEBUG ConnectionManager: Before Sending [BufferMessage(id = 20, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)] connectionid: joshs-mbp_58931_1
14/08/06 13:09:38.379 DEBUG ConnectionManager: Sending [BufferMessage(id = 20, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.379 DEBUG SendingConnection: Added [BufferMessage(id = 20, size = 10485760)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.380 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.380 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.380 DEBUG SendingConnection: Starting to send [BufferMessage(id = 20, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.380 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.381 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 20, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.381 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.381 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.382 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.382 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.382 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.382 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.382 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.383 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.384 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.384 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.385 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.386 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.386 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.386 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.387 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.387 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.388 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.388 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.389 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.389 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.389 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.389 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.390 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.390 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.390 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.390 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.391 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.391 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.391 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.391 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.392 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.392 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.392 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.392 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.394 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.394 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.394 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.394 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.395 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.395 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.395 DEBUG SendingConnection: Finished sending [BufferMessage(id = 20, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)] in 15 ms
14/08/06 13:09:38.395 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 20, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)] in 14 ms
14/08/06 13:09:38.396 DEBUG ConnectionManager: Received [BufferMessage(id = 20, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.396 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:09:38.396 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.396 DEBUG ConnectionManager: Handling [BufferMessage(id = 20, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.396 DEBUG ConnectionManager: Calling back
14/08/06 13:09:38.396 DEBUG ConnectionManager: Sasl already established
14/08/06 13:09:38.396 DEBUG ConnectionManager: Before Sending [BufferAckMessage(aid = 20, id = 21, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)] connectionid: joshs-mbp_58932_2
14/08/06 13:09:38.396 DEBUG ConnectionManager: Sending [BufferAckMessage(aid = 20, id = 21, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.396 DEBUG SendingConnection: Added [BufferAckMessage(aid = 20, id = 21, size = 0)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.397 DEBUG ConnectionManager: Handling delay is 1 ms
14/08/06 13:09:38.397 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.397 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.397 DEBUG SendingConnection: Starting to send [BufferAckMessage(aid = 20, id = 21, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.397 DEBUG SendingConnection: Finished sending [BufferAckMessage(aid = 20, id = 21, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)] in 0 ms
14/08/06 13:09:38.397 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.397 DEBUG ConnectionManager: Received [BufferAckMessage(aid = 20, id = 21, size = 0)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.397 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.397 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:09:38.398 DEBUG ConnectionManager: Handling [BufferAckMessage(aid = 20, id = 21, size = 0)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.398 DEBUG ConnectionManager: Handling delay is 1 ms
14/08/06 13:09:38.398 DEBUG ConnectionManager: Sasl already established
14/08/06 13:09:38.398 DEBUG ConnectionManager: Before Sending [BufferMessage(id = 22, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)] connectionid: joshs-mbp_58931_1
14/08/06 13:09:38.398 DEBUG ConnectionManager: Sending [BufferMessage(id = 22, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.398 DEBUG SendingConnection: Added [BufferMessage(id = 22, size = 10485760)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.398 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.398 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.398 DEBUG SendingConnection: Starting to send [BufferMessage(id = 22, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.398 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.399 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 22, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.399 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.400 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.400 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.400 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.401 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.401 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.402 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.402 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.402 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.402 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.403 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.403 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.403 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.403 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.404 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.404 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.405 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.406 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.406 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.406 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.406 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.407 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.407 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.407 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.407 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.407 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.408 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.408 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.408 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.408 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.408 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.408 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.409 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.409 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.409 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.409 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.409 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.409 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.410 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.410 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.410 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.410 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.411 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.411 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.411 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.411 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.412 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.412 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.412 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.412 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.412 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.412 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.413 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.413 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.413 DEBUG SendingConnection: Finished sending [BufferMessage(id = 22, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)] in 15 ms
14/08/06 13:09:38.413 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 22, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)] in 14 ms
14/08/06 13:09:38.413 DEBUG ConnectionManager: Received [BufferMessage(id = 22, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.413 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.413 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:09:38.413 DEBUG ConnectionManager: Handling [BufferMessage(id = 22, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.413 DEBUG ConnectionManager: Calling back
14/08/06 13:09:38.413 DEBUG ConnectionManager: Sasl already established
14/08/06 13:09:38.414 DEBUG ConnectionManager: Before Sending [BufferAckMessage(aid = 22, id = 23, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)] connectionid: joshs-mbp_58932_2
14/08/06 13:09:38.414 DEBUG ConnectionManager: Sending [BufferAckMessage(aid = 22, id = 23, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.414 DEBUG SendingConnection: Added [BufferAckMessage(aid = 22, id = 23, size = 0)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.414 DEBUG ConnectionManager: Handling delay is 1 ms
14/08/06 13:09:38.414 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.414 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.414 DEBUG SendingConnection: Starting to send [BufferAckMessage(aid = 22, id = 23, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.414 DEBUG SendingConnection: Finished sending [BufferAckMessage(aid = 22, id = 23, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)] in 0 ms
14/08/06 13:09:38.414 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.414 DEBUG ConnectionManager: Received [BufferAckMessage(aid = 22, id = 23, size = 0)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.414 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.415 DEBUG ConnectionManager: Handler thread delay is 1 ms
14/08/06 13:09:38.415 DEBUG ConnectionManager: Handling [BufferAckMessage(aid = 22, id = 23, size = 0)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.415 DEBUG ConnectionManager: Handling delay is 1 ms
14/08/06 13:09:38.415 DEBUG ConnectionManager: Sasl already established
14/08/06 13:09:38.415 DEBUG ConnectionManager: Before Sending [BufferMessage(id = 24, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)] connectionid: joshs-mbp_58931_1
14/08/06 13:09:38.415 DEBUG ConnectionManager: Sending [BufferMessage(id = 24, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.415 DEBUG SendingConnection: Added [BufferMessage(id = 24, size = 10485760)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.415 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.415 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.415 DEBUG SendingConnection: Starting to send [BufferMessage(id = 24, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.415 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.416 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.416 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 24, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.417 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.417 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.417 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.418 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.418 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.418 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.418 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.419 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.419 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.419 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.419 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.419 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.420 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.420 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.420 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.420 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.420 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.420 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.421 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.421 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.421 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.421 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.421 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.422 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.422 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.422 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.422 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.422 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.423 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.423 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.423 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.423 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.423 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.424 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.424 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.424 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.424 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.425 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.425 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.425 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.425 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.426 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.426 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.426 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.426 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.426 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.426 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.427 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.427 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.427 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.427 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.427 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.427 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.428 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.428 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.428 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.428 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.428 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.428 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.429 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.429 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.429 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.429 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.429 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.430 DEBUG SendingConnection: Finished sending [BufferMessage(id = 24, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)] in 14 ms
14/08/06 13:09:38.430 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.430 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 24, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)] in 14 ms
14/08/06 13:09:38.430 DEBUG ConnectionManager: Received [BufferMessage(id = 24, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.430 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.430 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:09:38.430 DEBUG ConnectionManager: Handling [BufferMessage(id = 24, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.430 DEBUG ConnectionManager: Calling back
14/08/06 13:09:38.430 DEBUG ConnectionManager: Sasl already established
14/08/06 13:09:38.430 DEBUG ConnectionManager: Before Sending [BufferAckMessage(aid = 24, id = 25, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)] connectionid: joshs-mbp_58932_2
14/08/06 13:09:38.430 DEBUG ConnectionManager: Sending [BufferAckMessage(aid = 24, id = 25, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.430 DEBUG SendingConnection: Added [BufferAckMessage(aid = 24, id = 25, size = 0)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.431 DEBUG ConnectionManager: Handling delay is 1 ms
14/08/06 13:09:38.431 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.431 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.431 DEBUG SendingConnection: Starting to send [BufferAckMessage(aid = 24, id = 25, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.431 DEBUG SendingConnection: Finished sending [BufferAckMessage(aid = 24, id = 25, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)] in 0 ms
14/08/06 13:09:38.431 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.431 DEBUG ConnectionManager: Received [BufferAckMessage(aid = 24, id = 25, size = 0)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.431 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.431 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:09:38.431 DEBUG ConnectionManager: Handling [BufferAckMessage(aid = 24, id = 25, size = 0)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.431 DEBUG ConnectionManager: Handling delay is 0 ms
14/08/06 13:09:38.432 DEBUG ConnectionManager: Sasl already established
14/08/06 13:09:38.432 DEBUG ConnectionManager: Before Sending [BufferMessage(id = 26, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)] connectionid: joshs-mbp_58931_1
14/08/06 13:09:38.432 DEBUG ConnectionManager: Sending [BufferMessage(id = 26, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.432 DEBUG SendingConnection: Added [BufferMessage(id = 26, size = 10485760)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.432 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.432 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.432 DEBUG SendingConnection: Starting to send [BufferMessage(id = 26, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.432 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.433 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.433 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 26, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.434 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.434 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.434 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.434 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.434 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.435 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.435 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.435 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.435 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.435 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.435 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.436 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.436 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.436 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.436 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.436 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.436 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.436 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.436 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.437 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.437 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.437 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.437 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.437 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.437 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.438 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.438 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.438 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.438 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.438 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.439 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.439 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.439 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.439 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.439 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.439 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.440 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.440 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.440 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.440 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.440 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.440 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.441 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.441 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.441 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.441 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.441 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.441 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.442 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.442 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.442 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.442 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.442 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.442 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.443 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.443 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.443 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.443 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.443 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.443 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.444 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.444 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.444 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.444 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.444 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.444 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.445 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.445 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.445 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.446 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.446 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.447 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.447 DEBUG SendingConnection: Finished sending [BufferMessage(id = 26, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)] in 15 ms
14/08/06 13:09:38.447 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.447 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 26, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)] in 14 ms
14/08/06 13:09:38.447 DEBUG ConnectionManager: Received [BufferMessage(id = 26, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.448 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.448 DEBUG ConnectionManager: Handler thread delay is 1 ms
14/08/06 13:09:38.448 DEBUG ConnectionManager: Handling [BufferMessage(id = 26, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.448 DEBUG ConnectionManager: Calling back
14/08/06 13:09:38.448 DEBUG ConnectionManager: Sasl already established
14/08/06 13:09:38.448 DEBUG ConnectionManager: Before Sending [BufferAckMessage(aid = 26, id = 27, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)] connectionid: joshs-mbp_58932_2
14/08/06 13:09:38.448 DEBUG ConnectionManager: Sending [BufferAckMessage(aid = 26, id = 27, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.448 DEBUG SendingConnection: Added [BufferAckMessage(aid = 26, id = 27, size = 0)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.448 DEBUG ConnectionManager: Handling delay is 1 ms
14/08/06 13:09:38.448 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.448 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.448 DEBUG SendingConnection: Starting to send [BufferAckMessage(aid = 26, id = 27, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.448 DEBUG SendingConnection: Finished sending [BufferAckMessage(aid = 26, id = 27, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)] in 0 ms
14/08/06 13:09:38.449 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.449 DEBUG ConnectionManager: Received [BufferAckMessage(aid = 26, id = 27, size = 0)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.449 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.449 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:09:38.449 DEBUG ConnectionManager: Handling [BufferAckMessage(aid = 26, id = 27, size = 0)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.449 DEBUG ConnectionManager: Handling delay is 0 ms
14/08/06 13:09:38.449 DEBUG ConnectionManager: Sasl already established
14/08/06 13:09:38.449 DEBUG ConnectionManager: Before Sending [BufferMessage(id = 28, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)] connectionid: joshs-mbp_58931_1
14/08/06 13:09:38.449 DEBUG ConnectionManager: Sending [BufferMessage(id = 28, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.449 DEBUG SendingConnection: Added [BufferMessage(id = 28, size = 10485760)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.450 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.450 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.450 DEBUG SendingConnection: Starting to send [BufferMessage(id = 28, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.450 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.451 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.451 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 28, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.451 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.452 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.452 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.452 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.452 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.453 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.453 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.453 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.453 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.454 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.454 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.454 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.454 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.455 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.455 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.455 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.455 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.455 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.455 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.456 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.456 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.456 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.456 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.456 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.457 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.457 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.457 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.457 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.457 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.457 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.458 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.458 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.458 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.458 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.458 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.459 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.459 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.459 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.459 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.459 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.459 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.460 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.460 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.460 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.460 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.460 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.460 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.461 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.461 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.461 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.461 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.462 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.462 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.462 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.462 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.462 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.462 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.463 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.463 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.463 DEBUG SendingConnection: Finished sending [BufferMessage(id = 28, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)] in 13 ms
14/08/06 13:09:38.463 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 28, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)] in 12 ms
14/08/06 13:09:38.463 DEBUG ConnectionManager: Received [BufferMessage(id = 28, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.464 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.464 DEBUG ConnectionManager: Handler thread delay is 1 ms
14/08/06 13:09:38.464 DEBUG ConnectionManager: Handling [BufferMessage(id = 28, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.464 DEBUG ConnectionManager: Calling back
14/08/06 13:09:38.464 DEBUG ConnectionManager: Sasl already established
14/08/06 13:09:38.464 DEBUG ConnectionManager: Before Sending [BufferAckMessage(aid = 28, id = 29, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)] connectionid: joshs-mbp_58932_2
14/08/06 13:09:38.464 DEBUG ConnectionManager: Sending [BufferAckMessage(aid = 28, id = 29, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.464 DEBUG SendingConnection: Added [BufferAckMessage(aid = 28, id = 29, size = 0)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.464 DEBUG ConnectionManager: Handling delay is 1 ms
14/08/06 13:09:38.464 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.464 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.464 DEBUG SendingConnection: Starting to send [BufferAckMessage(aid = 28, id = 29, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.464 DEBUG SendingConnection: Finished sending [BufferAckMessage(aid = 28, id = 29, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)] in 0 ms
14/08/06 13:09:38.464 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.465 DEBUG ConnectionManager: Received [BufferAckMessage(aid = 28, id = 29, size = 0)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.465 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.465 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:09:38.465 DEBUG ConnectionManager: Handling [BufferAckMessage(aid = 28, id = 29, size = 0)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.465 DEBUG ConnectionManager: Handling delay is 0 ms
14/08/06 13:09:38.465 DEBUG ConnectionManager: Sasl already established
14/08/06 13:09:38.465 DEBUG ConnectionManager: Before Sending [BufferMessage(id = 30, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)] connectionid: joshs-mbp_58931_1
14/08/06 13:09:38.465 DEBUG ConnectionManager: Sending [BufferMessage(id = 30, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.465 DEBUG SendingConnection: Added [BufferMessage(id = 30, size = 10485760)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.465 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.465 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.466 DEBUG SendingConnection: Starting to send [BufferMessage(id = 30, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.466 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.467 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.467 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 30, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.467 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.469 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.469 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.469 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.469 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.469 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.470 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.470 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.470 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.470 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.471 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.471 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.471 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.471 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.471 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.472 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.472 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.472 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.472 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.473 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.473 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.473 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.473 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.474 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.474 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.474 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.474 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.474 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.474 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.475 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.475 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.475 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.475 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.475 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.476 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.476 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.476 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.476 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.476 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.477 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.477 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.477 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.477 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.477 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.478 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.478 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.478 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.478 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.478 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.479 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.479 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.479 DEBUG SendingConnection: Finished sending [BufferMessage(id = 30, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58932)] in 13 ms
14/08/06 13:09:38.479 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 30, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)] in 12 ms
14/08/06 13:09:38.479 DEBUG ConnectionManager: Received [BufferMessage(id = 30, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.479 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.479 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:09:38.479 DEBUG ConnectionManager: Handling [BufferMessage(id = 30, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.480 DEBUG ConnectionManager: Calling back
14/08/06 13:09:38.480 DEBUG ConnectionManager: Sasl already established
14/08/06 13:09:38.480 DEBUG ConnectionManager: Before Sending [BufferAckMessage(aid = 30, id = 31, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)] connectionid: joshs-mbp_58932_2
14/08/06 13:09:38.480 DEBUG ConnectionManager: Sending [BufferAckMessage(aid = 30, id = 31, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.480 DEBUG SendingConnection: Added [BufferAckMessage(aid = 30, id = 31, size = 0)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.480 DEBUG ConnectionManager: Handling delay is 1 ms
14/08/06 13:09:38.480 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.480 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.480 DEBUG SendingConnection: Starting to send [BufferAckMessage(aid = 30, id = 31, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)]
14/08/06 13:09:38.480 DEBUG SendingConnection: Finished sending [BufferAckMessage(aid = 30, id = 31, size = 0)] to [ConnectionManagerId(joshs-mbp,58931)] in 0 ms
14/08/06 13:09:38.480 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.480 DEBUG ConnectionManager: Received [BufferAckMessage(aid = 30, id = 31, size = 0)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.481 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.481 DEBUG ConnectionManager: Handler thread delay is 1 ms
14/08/06 13:09:38.481 DEBUG ConnectionManager: Handling [BufferAckMessage(aid = 30, id = 31, size = 0)] from [ConnectionManagerId(joshs-mbp,58932)]
14/08/06 13:09:38.481 DEBUG ConnectionManager: Handling delay is 1 ms
14/08/06 13:09:38.481 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.481 INFO ConnectionManager: Selector thread was interrupted!
14/08/06 13:09:38.481 INFO ConnectionManager: Removing SendingConnection to ConnectionManagerId(joshs-mbp,58932)
14/08/06 13:09:38.481 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.481 INFO ConnectionManager: Removing ReceivingConnection to ConnectionManagerId(joshs-mbp,58931)
14/08/06 13:09:38.481 INFO ConnectionManager: Removing ReceivingConnection to ConnectionManagerId(joshs-mbp,58932)
14/08/06 13:09:38.481 ERROR ConnectionManager: Corresponding SendingConnectionManagerId not found
14/08/06 13:09:38.481 INFO ConnectionManager: Removing SendingConnection to ConnectionManagerId(joshs-mbp,58931)
14/08/06 13:09:38.481 WARN ConnectionManager: All connections not cleaned up
14/08/06 13:09:38.481 INFO ConnectionManager: ConnectionManager stopped
14/08/06 13:09:38.482 INFO ConnectionManager: Key not valid ? sun.nio.ch.SelectionKeyImpl@339ec220
14/08/06 13:09:38.482 ERROR SendingConnection: Exception while reading SendingConnection to ConnectionManagerId(joshs-mbp,58931)
java.nio.channels.ClosedChannelException
at sun.nio.ch.SocketChannelImpl.ensureReadOpen(SocketChannelImpl.java:252)
at sun.nio.ch.SocketChannelImpl.read(SocketChannelImpl.java:295)
at org.apache.spark.network.SendingConnection.read(Connection.scala:390)
at org.apache.spark.network.ConnectionManager$$anon$6.run(ConnectionManager.scala:179)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
14/08/06 13:09:38.482 INFO ConnectionManager: key already cancelled ? sun.nio.ch.SelectionKeyImpl@339ec220
java.nio.channels.CancelledKeyException
at org.apache.spark.network.ConnectionManager.run(ConnectionManager.scala:366)
at org.apache.spark.network.ConnectionManager$$anon$4.run(ConnectionManager.scala:119)
14/08/06 13:09:38.483 INFO ConnectionManager: Handling connection error on connection to ConnectionManagerId(joshs-mbp,58931)
14/08/06 13:09:38.483 INFO ConnectionManager: Removing SendingConnection to ConnectionManagerId(joshs-mbp,58931)
14/08/06 13:09:38.483 DEBUG ConnectionManager: Ignoring exception
java.nio.channels.CancelledKeyException
at sun.nio.ch.SelectionKeyImpl.ensureValid(SelectionKeyImpl.java:73)
at sun.nio.ch.SelectionKeyImpl.interestOps(SelectionKeyImpl.java:82)
at org.apache.spark.network.ConnectionManager.triggerForceCloseByException(ConnectionManager.scala:223)
at org.apache.spark.network.ConnectionManager.run(ConnectionManager.scala:373)
at org.apache.spark.network.ConnectionManager$$anon$4.run(ConnectionManager.scala:119)
14/08/06 13:09:38.484 INFO ConnectionManager: Removing SendingConnection to ConnectionManagerId(joshs-mbp,58931)
14/08/06 13:09:38.484 INFO ConnectionManager: ConnectionManager stopped
14/08/06 13:09:38.486 INFO SecurityManager: Changing view acls to: joshrosen
14/08/06 13:09:38.486 INFO SecurityManager: SecurityManager: authentication enabled; ui acls disabled; users with view permissions: Set(joshrosen)
14/08/06 13:09:38.487 INFO ConnectionManager: Bound socket to port 58935 with id = ConnectionManagerId(joshs-mbp,58935)
14/08/06 13:09:38.487 INFO SecurityManager: Changing view acls to: joshrosen
14/08/06 13:09:38.487 INFO SecurityManager: SecurityManager: authentication enabled; ui acls disabled; users with view permissions: Set(joshrosen)
14/08/06 13:09:38.488 INFO ConnectionManager: Bound socket to port 58936 with id = ConnectionManagerId(joshs-mbp,58936)
14/08/06 13:09:38.614 DEBUG SecurityMessage: message total size is : 42
14/08/06 13:09:38.614 DEBUG SendingConnection: Added [BufferMessage(id = 33, size = 42)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58936)]
14/08/06 13:09:38.614 DEBUG ConnectionManager: adding connectionsAwaitingSasl id: joshs-mbp_58935_1
14/08/06 13:09:38.614 DEBUG ConnectionManager: Selector selected 0 of 1 keys
14/08/06 13:09:38.615 DEBUG ConnectionManager: Before Sending [BufferMessage(id = 32, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58936)] connectionid: joshs-mbp_58935_1
14/08/06 13:09:38.615 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:38.615 INFO SendingConnection: Initiating connection to [joshs-mbp/192.168.1.245:58936]
14/08/06 13:09:38.615 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:09:38.615 INFO SendingConnection: Connected to [joshs-mbp/192.168.1.245:58936], 1 messages pending
14/08/06 13:09:38.615 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:09:38.615 INFO ConnectionManager: Accepted connection from [joshs-mbp/192.168.1.245]
14/08/06 13:09:38.615 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:09:38.616 DEBUG SendingConnection: Starting to send [BufferMessage(id = 33, size = 42)] to [ConnectionManagerId(joshs-mbp,58936)]
14/08/06 13:09:38.616 DEBUG SendingConnection: Finished sending [BufferMessage(id = 33, size = 42)] to [ConnectionManagerId(joshs-mbp,58936)] in 0 ms
14/08/06 13:09:38.616 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:09:38.616 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 33, size = 42)] from [ConnectionManagerId(joshs-mbp,58935)]
14/08/06 13:09:38.616 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 33, size = 42)] from [ConnectionManagerId(joshs-mbp,58935)] in 0 ms
14/08/06 13:09:38.617 DEBUG ConnectionManager: Received [BufferMessage(id = 33, size = 42)] from [ConnectionManagerId(joshs-mbp,58935)]
14/08/06 13:09:38.617 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:09:38.617 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:09:38.617 DEBUG ConnectionManager: Handling [BufferMessage(id = 33, size = 42)] from [ConnectionManagerId(joshs-mbp,58935)]
14/08/06 13:09:38.617 DEBUG ConnectionManager: This is security neg message
14/08/06 13:09:38.617 DEBUG ConnectionManager: Server handleAuth for id: joshs-mbp_58935_1
14/08/06 13:09:38.617 DEBUG ConnectionManager: saslContext not established
14/08/06 13:09:38.617 DEBUG ConnectionManager: Creating sasl Server
14/08/06 13:09:38.618 DEBUG ConnectionManager: Server sasl not completed: joshs-mbp_58936_1
14/08/06 13:09:38.618 DEBUG SecurityMessage: message total size is : 150
14/08/06 13:09:38.618 INFO ConnectionManager: creating new sending connection for security! joshs-mbp_58936_2
14/08/06 13:09:38.618 DEBUG SendingConnection: Added [BufferMessage(id = 34, size = 150)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58935)]
14/08/06 13:09:38.618 DEBUG ConnectionManager: After handleAuth result was true, returning
14/08/06 13:09:38.618 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:09:38.619 INFO SendingConnection: Initiating connection to [joshs-mbp/192.168.1.245:58935]
14/08/06 13:09:38.618 DEBUG ConnectionManager: Handling delay is 1 ms
14/08/06 13:09:38.619 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.620 INFO SendingConnection: Connected to [joshs-mbp/192.168.1.245:58935], 1 messages pending
14/08/06 13:09:38.620 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.620 INFO ConnectionManager: Accepted connection from [joshs-mbp/192.168.1.245]
14/08/06 13:09:38.620 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.620 DEBUG SendingConnection: Starting to send [BufferMessage(id = 34, size = 150)] to [ConnectionManagerId(joshs-mbp,58935)]
14/08/06 13:09:38.620 DEBUG SendingConnection: Finished sending [BufferMessage(id = 34, size = 150)] to [ConnectionManagerId(joshs-mbp,58935)] in 0 ms
14/08/06 13:09:38.621 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.621 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 34, size = 150)] from [ConnectionManagerId(joshs-mbp,58936)]
14/08/06 13:09:38.621 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 34, size = 150)] from [ConnectionManagerId(joshs-mbp,58936)] in 0 ms
14/08/06 13:09:38.621 DEBUG ConnectionManager: Received [BufferMessage(id = 34, size = 150)] from [ConnectionManagerId(joshs-mbp,58936)]
14/08/06 13:09:38.622 DEBUG ConnectionManager: Handler thread delay is 1 ms
14/08/06 13:09:38.622 DEBUG ConnectionManager: Handling [BufferMessage(id = 34, size = 150)] from [ConnectionManagerId(joshs-mbp,58936)]
14/08/06 13:09:38.622 DEBUG ConnectionManager: This is security neg message
14/08/06 13:09:38.622 DEBUG ConnectionManager: Client handleAuth for id: joshs-mbp_58935_1
14/08/06 13:09:38.622 DEBUG SparkSaslClient: in the sasl client callback handler
14/08/06 13:09:38.622 DEBUG SparkSaslClient: handle: SASL client callback: setting realm: default
14/08/06 13:09:38.622 DEBUG SparkSaslClient: handle: SASL client callback: setting username: c3BhcmtTYXNsVXNlcg==
14/08/06 13:09:38.622 DEBUG SparkSaslClient: handle: SASL client callback: setting userPassword
14/08/06 13:09:38.622 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.623 DEBUG SecurityMessage: message total size is : 304
14/08/06 13:09:38.623 DEBUG SendingConnection: Added [BufferMessage(id = 35, size = 304)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58936)]
14/08/06 13:09:38.623 DEBUG ConnectionManager: After handleAuth result was true, returning
14/08/06 13:09:38.623 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.623 DEBUG ConnectionManager: Handling delay is 2 ms
14/08/06 13:09:38.623 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.623 DEBUG SendingConnection: Starting to send [BufferMessage(id = 35, size = 304)] to [ConnectionManagerId(joshs-mbp,58936)]
14/08/06 13:09:38.623 DEBUG SendingConnection: Finished sending [BufferMessage(id = 35, size = 304)] to [ConnectionManagerId(joshs-mbp,58936)] in 0 ms
14/08/06 13:09:38.624 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.624 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 35, size = 304)] from [ConnectionManagerId(joshs-mbp,58935)]
14/08/06 13:09:38.624 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 35, size = 304)] from [ConnectionManagerId(joshs-mbp,58935)] in 0 ms
14/08/06 13:09:38.624 DEBUG ConnectionManager: Received [BufferMessage(id = 35, size = 304)] from [ConnectionManagerId(joshs-mbp,58935)]
14/08/06 13:09:38.624 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:09:38.624 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:09:38.624 DEBUG ConnectionManager: Handling [BufferMessage(id = 35, size = 304)] from [ConnectionManagerId(joshs-mbp,58935)]
14/08/06 13:09:38.624 DEBUG ConnectionManager: This is security neg message
14/08/06 13:09:38.624 DEBUG ConnectionManager: Server handleAuth for id: joshs-mbp_58935_1
14/08/06 13:09:38.624 DEBUG ConnectionManager: saslContext not established
14/08/06 13:09:38.625 DEBUG SparkSaslServer: In the sasl server callback handler
14/08/06 13:09:38.625 DEBUG SparkSaslServer: handle: SASL server callback: setting realm: default
14/08/06 13:09:38.625 DEBUG SparkSaslServer: handle: SASL server callback: setting username
14/08/06 13:09:38.625 DEBUG SparkSaslServer: handle: SASL server callback: setting userPassword
14/08/06 13:09:38.625 ERROR ConnectionManager: Error in server auth negotiation: javax.security.sasl.SaslException: DIGEST-MD5: digest response format violation. Mismatched response.
14/08/06 13:09:38.625 INFO ConnectionManager: Removing ReceivingConnection to ConnectionManagerId(joshs-mbp,58935)
14/08/06 13:09:38.625 INFO ConnectionManager: Removing SendingConnection to ConnectionManagerId(joshs-mbp,58935)
14/08/06 13:09:38.626 DEBUG ConnectionManager: After handleAuth result was true, returning
14/08/06 13:09:38.626 DEBUG ConnectionManager: Handling delay is 2 ms
14/08/06 13:09:38.626 DEBUG ConnectionManager: Selector selected 0 of 1 keys
14/08/06 13:09:38.626 INFO ConnectionManager: Removing SendingConnection to ConnectionManagerId(joshs-mbp,58936)
14/08/06 13:09:38.626 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:09:38.626 INFO ConnectionManager: Removing ReceivingConnection to ConnectionManagerId(joshs-mbp,58936)
14/08/06 13:09:38.626 ERROR ConnectionManager: Corresponding SendingConnectionManagerId not found
14/08/06 13:09:38.626 DEBUG ConnectionManager: Selector selected 0 of 1 keys
14/08/06 13:09:38.626 INFO ConnectionManager: Notifying org.apache.spark.network.ConnectionManager$MessageStatus@3b1f9e7a
14/08/06 13:09:38.626 DEBUG ConnectionManager: Selector selected 0 of 1 keys
14/08/06 13:09:39.115 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:39.616 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:40.118 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:40.618 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:41.119 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:41.620 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:42.120 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:42.621 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:43.122 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:43.623 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:44.124 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:44.625 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:45.126 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:45.626 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:46.127 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:46.629 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:47.129 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:47.631 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:48.131 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:48.632 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:49.133 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:49.633 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:50.135 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:50.635 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:51.136 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:51.637 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:52.138 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:52.638 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:53.139 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:53.640 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:54.141 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:54.642 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:55.142 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:55.643 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:56.145 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:56.645 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:57.146 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:57.647 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:58.147 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:58.648 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:59.149 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:09:59.650 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:10:00.150 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:10:00.652 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:10:01.153 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:10:01.653 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:10:02.154 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:10:02.655 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:10:03.155 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:10:03.657 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:10:04.157 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:10:04.658 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:10:05.159 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:10:05.660 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:10:06.161 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:10:06.663 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:10:07.164 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:10:07.665 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:10:08.166 DEBUG ConnectionManager: getAuthenticated wait connectionid: joshs-mbp_58935_1
14/08/06 13:10:08.668 ERROR ConnectionManager: Exception while waiting for authentication.
java.lang.Exception: Took to long for authentication to ConnectionManagerId(joshs-mbp,58936), waited 30seconds, failing.
at org.apache.spark.network.ConnectionManager.sendMessage(ConnectionManager.scala:790)
at org.apache.spark.network.ConnectionManager.sendMessageReliably(ConnectionManager.scala:836)
at org.apache.spark.network.ConnectionManager.sendMessageReliablySync(ConnectionManager.scala:842)
at org.apache.spark.network.ConnectionManagerSuite$$anonfun$3.apply$mcV$sp(ConnectionManagerSuite.scala:121)
at org.apache.spark.network.ConnectionManagerSuite$$anonfun$3.apply(ConnectionManagerSuite.scala:92)
at org.apache.spark.network.ConnectionManagerSuite$$anonfun$3.apply(ConnectionManagerSuite.scala:92)
at org.scalatest.Transformer$$anonfun$apply$1.apply(Transformer.scala:22)
at org.scalatest.Transformer$$anonfun$apply$1.apply(Transformer.scala:22)
at org.scalatest.OutcomeOf$class.outcomeOf(OutcomeOf.scala:85)
at org.scalatest.OutcomeOf$.outcomeOf(OutcomeOf.scala:104)
at org.scalatest.Transformer.apply(Transformer.scala:22)
at org.scalatest.Transformer.apply(Transformer.scala:20)
at org.scalatest.FunSuiteLike$$anon$1.apply(FunSuiteLike.scala:158)
at org.scalatest.Suite$class.withFixture(Suite.scala:1121)
at org.scalatest.FunSuite.withFixture(FunSuite.scala:1559)
at org.scalatest.FunSuiteLike$class.invokeWithFixture$1(FunSuiteLike.scala:155)
at org.scalatest.FunSuiteLike$$anonfun$runTest$1.apply(FunSuiteLike.scala:167)
at org.scalatest.FunSuiteLike$$anonfun$runTest$1.apply(FunSuiteLike.scala:167)
at org.scalatest.SuperEngine.runTestImpl(Engine.scala:306)
at org.scalatest.FunSuiteLike$class.runTest(FunSuiteLike.scala:167)
at org.scalatest.FunSuite.runTest(FunSuite.scala:1559)
at org.scalatest.FunSuiteLike$$anonfun$runTests$1.apply(FunSuiteLike.scala:200)
at org.scalatest.FunSuiteLike$$anonfun$runTests$1.apply(FunSuiteLike.scala:200)
at org.scalatest.SuperEngine$$anonfun$traverseSubNodes$1$1.apply(Engine.scala:413)
at org.scalatest.SuperEngine$$anonfun$traverseSubNodes$1$1.apply(Engine.scala:401)
at scala.collection.immutable.List.foreach(List.scala:318)
at org.scalatest.SuperEngine.traverseSubNodes$1(Engine.scala:401)
at org.scalatest.SuperEngine.org$scalatest$SuperEngine$$runTestsInBranch(Engine.scala:396)
at org.scalatest.SuperEngine.runTestsImpl(Engine.scala:483)
at org.scalatest.FunSuiteLike$class.runTests(FunSuiteLike.scala:200)
at org.scalatest.FunSuite.runTests(FunSuite.scala:1559)
at org.scalatest.Suite$class.run(Suite.scala:1423)
at org.scalatest.FunSuite.org$scalatest$FunSuiteLike$$super$run(FunSuite.scala:1559)
at org.scalatest.FunSuiteLike$$anonfun$run$1.apply(FunSuiteLike.scala:204)
at org.scalatest.FunSuiteLike$$anonfun$run$1.apply(FunSuiteLike.scala:204)
at org.scalatest.SuperEngine.runImpl(Engine.scala:545)
at org.scalatest.FunSuiteLike$class.run(FunSuiteLike.scala:204)
at org.scalatest.FunSuite.run(FunSuite.scala:1559)
at org.scalatest.tools.Framework.org$scalatest$tools$Framework$$runSuite(Framework.scala:444)
at org.scalatest.tools.Framework$ScalaTestTask.execute(Framework.scala:651)
at sbt.ForkMain$Run$2.call(ForkMain.java:294)
at sbt.ForkMain$Run$2.call(ForkMain.java:284)
at java.util.concurrent.FutureTask.run(FutureTask.java:262)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
14/08/06 13:10:08.669 ERROR ConnectionManager: no messageStatus for failed message id: 32
14/08/06 13:10:08.669 DEBUG ConnectionManager: Sending [BufferMessage(id = 32, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58936)]
14/08/06 13:10:08.669 DEBUG SendingConnection: Added [BufferMessage(id = 32, size = 10485760)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58936)]
14/08/06 13:10:08.669 DEBUG ConnectionManager: Selector selected 0 of 1 keys
14/08/06 13:10:08.669 INFO ConnectionManager: Selector thread was interrupted!
14/08/06 13:10:08.670 INFO ConnectionManager: ConnectionManager stopped
14/08/06 13:10:08.670 DEBUG ConnectionManager: Selector selected 0 of 1 keys
14/08/06 13:10:08.670 INFO ConnectionManager: Selector thread was interrupted!
14/08/06 13:10:08.670 INFO ConnectionManager: ConnectionManager stopped
14/08/06 13:10:08.671 INFO SecurityManager: Changing view acls to: joshrosen
14/08/06 13:10:08.671 INFO SecurityManager: SecurityManager: authentication disabled; ui acls disabled; users with view permissions: Set(joshrosen)
14/08/06 13:10:08.672 INFO ConnectionManager: Bound socket to port 58974 with id = ConnectionManagerId(joshs-mbp,58974)
14/08/06 13:10:08.673 INFO SecurityManager: Changing view acls to: joshrosen
14/08/06 13:10:08.673 INFO SecurityManager: SecurityManager: authentication enabled; ui acls disabled; users with view permissions: Set(joshrosen)
14/08/06 13:10:08.673 INFO ConnectionManager: Bound socket to port 58975 with id = ConnectionManagerId(joshs-mbp,58975)
14/08/06 13:10:09.106 DEBUG ConnectionManager: Before Sending [BufferMessage(id = 37, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58975)] connectionid: joshs-mbp_58974_1
14/08/06 13:10:09.106 DEBUG ConnectionManager: Sending [BufferMessage(id = 37, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58975)]
14/08/06 13:10:09.106 DEBUG SendingConnection: Added [BufferMessage(id = 37, size = 10485760)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58975)]
14/08/06 13:10:09.107 DEBUG ConnectionManager: Selector selected 0 of 1 keys
14/08/06 13:10:09.107 INFO SendingConnection: Initiating connection to [joshs-mbp/192.168.1.245:58975]
14/08/06 13:10:09.107 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.108 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.108 INFO SendingConnection: Connected to [joshs-mbp/192.168.1.245:58975], 1 messages pending
14/08/06 13:10:09.108 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.109 DEBUG SendingConnection: Starting to send [BufferMessage(id = 37, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58975)]
14/08/06 13:10:09.109 INFO ConnectionManager: Accepted connection from [joshs-mbp/192.168.1.245]
14/08/06 13:10:09.110 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.110 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.111 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 37, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58974)]
14/08/06 13:10:09.112 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.113 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.113 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.114 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.114 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.114 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.115 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.115 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.115 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.116 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.116 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.116 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.116 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.117 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.117 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.117 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.117 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.118 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.118 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.118 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.118 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.118 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.118 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.119 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.119 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.119 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.119 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.119 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.119 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.120 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.120 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.120 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.120 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.120 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.121 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.121 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.121 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.121 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.121 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.122 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.122 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.122 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.122 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.123 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.123 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.123 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.123 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.123 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.123 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.124 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.124 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.124 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.124 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.125 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.125 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.126 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.126 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.127 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.127 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.127 DEBUG SendingConnection: Finished sending [BufferMessage(id = 37, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58975)] in 18 ms
14/08/06 13:10:09.127 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 37, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58974)] in 16 ms
14/08/06 13:10:09.127 DEBUG ConnectionManager: Received [BufferMessage(id = 37, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58974)]
14/08/06 13:10:09.127 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:09.127 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:10:09.127 DEBUG ConnectionManager: Handling [BufferMessage(id = 37, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58974)]
14/08/06 13:10:09.128 ERROR ConnectionManager: message sent that is not security negotiation message on connection not authenticated yet, ignoring it!!
14/08/06 13:10:09.128 DEBUG ConnectionManager: After handleAuth result was true, returning
14/08/06 13:10:09.128 DEBUG ConnectionManager: Handling delay is 1 ms
14/08/06 13:10:10.116 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.116 INFO ConnectionManager: Selector thread was interrupted!
14/08/06 13:10:10.117 INFO ConnectionManager: Removing SendingConnection to ConnectionManagerId(joshs-mbp,58975)
14/08/06 13:10:10.117 INFO ConnectionManager: Notifying org.apache.spark.network.ConnectionManager$MessageStatus@de0a6c
14/08/06 13:10:10.117 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.117 INFO ConnectionManager: Removing ReceivingConnection to ConnectionManagerId(joshs-mbp,58974)
14/08/06 13:10:10.117 WARN ConnectionManager: All connections not cleaned up
14/08/06 13:10:10.117 ERROR ConnectionManager: Corresponding SendingConnectionManagerId not found
14/08/06 13:10:10.118 DEBUG ConnectionManager: Selector selected 0 of 1 keys
14/08/06 13:10:10.118 INFO ConnectionManager: ConnectionManager stopped
14/08/06 13:10:10.118 DEBUG ConnectionManager: Selector selected 0 of 1 keys
14/08/06 13:10:10.118 INFO ConnectionManager: Selector thread was interrupted!
14/08/06 13:10:10.118 INFO ConnectionManager: ConnectionManager stopped
14/08/06 13:10:10.120 INFO SecurityManager: Changing view acls to: joshrosen
14/08/06 13:10:10.120 INFO SecurityManager: SecurityManager: authentication disabled; ui acls disabled; users with view permissions: Set(joshrosen)
14/08/06 13:10:10.120 INFO ConnectionManager: Bound socket to port 58977 with id = ConnectionManagerId(joshs-mbp,58977)
14/08/06 13:10:10.121 INFO SecurityManager: Changing view acls to: joshrosen
14/08/06 13:10:10.121 INFO SecurityManager: SecurityManager: authentication disabled; ui acls disabled; users with view permissions: Set(joshrosen)
14/08/06 13:10:10.122 INFO ConnectionManager: Bound socket to port 58978 with id = ConnectionManagerId(joshs-mbp,58978)
14/08/06 13:10:10.199 DEBUG ConnectionManager: Before Sending [BufferMessage(id = 39, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)] connectionid: joshs-mbp_58977_1
14/08/06 13:10:10.199 DEBUG ConnectionManager: Sending [BufferMessage(id = 39, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.199 DEBUG SendingConnection: Added [BufferMessage(id = 39, size = 10485760)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.199 DEBUG ConnectionManager: Before Sending [BufferMessage(id = 40, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)] connectionid: joshs-mbp_58977_1
14/08/06 13:10:10.199 DEBUG ConnectionManager: Selector selected 0 of 1 keys
14/08/06 13:10:10.199 DEBUG ConnectionManager: Sending [BufferMessage(id = 40, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.199 DEBUG SendingConnection: Added [BufferMessage(id = 40, size = 10485760)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.199 INFO SendingConnection: Initiating connection to [joshs-mbp/192.168.1.245:58978]
14/08/06 13:10:10.199 DEBUG ConnectionManager: Before Sending [BufferMessage(id = 41, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)] connectionid: joshs-mbp_58977_1
14/08/06 13:10:10.199 DEBUG ConnectionManager: Sending [BufferMessage(id = 41, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.200 DEBUG SendingConnection: Added [BufferMessage(id = 41, size = 10485760)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.200 DEBUG ConnectionManager: Before Sending [BufferMessage(id = 42, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)] connectionid: joshs-mbp_58977_1
14/08/06 13:10:10.200 DEBUG ConnectionManager: Sending [BufferMessage(id = 42, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.200 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.200 DEBUG SendingConnection: Added [BufferMessage(id = 42, size = 10485760)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.200 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.200 INFO SendingConnection: Connected to [joshs-mbp/192.168.1.245:58978], 4 messages pending
14/08/06 13:10:10.200 DEBUG ConnectionManager: Before Sending [BufferMessage(id = 43, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)] connectionid: joshs-mbp_58977_1
14/08/06 13:10:10.200 INFO ConnectionManager: Accepted connection from [joshs-mbp/192.168.1.245]
14/08/06 13:10:10.200 DEBUG ConnectionManager: Sending [BufferMessage(id = 43, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.200 DEBUG SendingConnection: Added [BufferMessage(id = 43, size = 10485760)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.200 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.200 DEBUG ConnectionManager: Before Sending [BufferMessage(id = 44, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)] connectionid: joshs-mbp_58977_1
14/08/06 13:10:10.201 DEBUG SendingConnection: Starting to send [BufferMessage(id = 39, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.201 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.201 DEBUG ConnectionManager: Sending [BufferMessage(id = 44, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.201 DEBUG SendingConnection: Added [BufferMessage(id = 44, size = 10485760)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.201 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.201 DEBUG ConnectionManager: Before Sending [BufferMessage(id = 45, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)] connectionid: joshs-mbp_58977_1
14/08/06 13:10:10.201 DEBUG SendingConnection: Starting to send [BufferMessage(id = 40, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.201 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.201 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.201 DEBUG ConnectionManager: Sending [BufferMessage(id = 45, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.201 DEBUG SendingConnection: Starting to send [BufferMessage(id = 41, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.201 DEBUG SendingConnection: Added [BufferMessage(id = 45, size = 10485760)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.201 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.201 DEBUG SendingConnection: Starting to send [BufferMessage(id = 42, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.201 DEBUG ConnectionManager: Before Sending [BufferMessage(id = 46, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)] connectionid: joshs-mbp_58977_1
14/08/06 13:10:10.202 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.202 DEBUG ConnectionManager: Sending [BufferMessage(id = 46, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.202 DEBUG SendingConnection: Starting to send [BufferMessage(id = 43, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.202 DEBUG SendingConnection: Added [BufferMessage(id = 46, size = 10485760)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.202 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.202 DEBUG ConnectionManager: Before Sending [BufferMessage(id = 47, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)] connectionid: joshs-mbp_58977_1
14/08/06 13:10:10.202 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.202 DEBUG ConnectionManager: Sending [BufferMessage(id = 47, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.202 DEBUG SendingConnection: Starting to send [BufferMessage(id = 44, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.202 DEBUG SendingConnection: Added [BufferMessage(id = 47, size = 10485760)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.202 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.202 DEBUG ConnectionManager: Before Sending [BufferMessage(id = 48, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)] connectionid: joshs-mbp_58977_1
14/08/06 13:10:10.202 DEBUG ConnectionManager: Sending [BufferMessage(id = 48, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.202 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.202 DEBUG SendingConnection: Added [BufferMessage(id = 48, size = 10485760)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.202 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.202 DEBUG SendingConnection: Starting to send [BufferMessage(id = 45, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.202 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 39, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.202 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.203 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.203 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 40, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.205 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 41, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.206 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 42, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.207 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 43, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.207 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.208 DEBUG SendingConnection: Starting to send [BufferMessage(id = 46, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.208 DEBUG SendingConnection: Starting to send [BufferMessage(id = 47, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.208 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 44, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.208 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.209 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 45, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.210 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.210 DEBUG SendingConnection: Starting to send [BufferMessage(id = 48, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.210 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 46, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.210 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.212 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 47, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.213 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 48, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.213 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.214 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.214 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.215 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.215 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.216 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.216 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.217 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.217 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.217 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.217 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.218 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.218 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.219 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.219 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.220 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.220 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.220 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.221 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.221 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.221 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.221 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.221 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.222 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.222 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.223 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.223 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.223 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.223 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.223 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.224 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.224 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.224 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.224 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.224 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.225 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.225 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.225 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.225 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.225 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.226 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.226 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.226 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.226 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.226 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.226 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.227 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.227 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.227 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.227 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.227 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.227 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.228 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.228 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.228 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.228 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.228 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.229 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.229 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.229 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.229 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.229 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.230 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.230 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.230 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.230 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.230 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.230 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.230 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.230 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.231 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.231 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.231 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.231 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.231 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.231 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.231 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.231 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.231 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.232 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.232 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.232 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.232 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.232 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.232 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.233 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.233 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.233 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.233 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.233 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.234 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.234 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.234 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.234 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.234 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.234 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.235 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.235 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.235 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.235 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.235 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.236 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.236 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.236 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.236 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.237 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.237 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.237 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.237 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.238 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.238 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.238 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.238 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.239 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.239 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.239 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.239 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.239 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.240 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.240 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.240 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.240 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.240 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.241 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.241 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.241 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.241 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.241 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.241 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.242 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.242 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.242 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.242 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.242 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.242 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.243 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.243 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.243 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.243 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.243 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.243 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.244 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.244 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.244 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.244 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.245 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.245 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.245 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.246 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.246 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.246 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.247 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.248 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.248 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.248 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.249 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.249 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.251 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.251 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.252 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.252 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.253 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.254 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.254 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.254 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.255 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.255 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.255 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.255 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.256 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.256 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.256 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.256 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.256 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.257 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.257 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.257 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.257 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.257 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.258 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.258 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.258 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.258 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.258 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.258 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.259 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.259 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.259 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.259 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.259 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.259 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.260 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.260 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.260 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.260 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.260 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.261 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.261 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.261 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.261 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.261 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.261 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.262 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.262 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.262 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.262 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.262 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.263 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.263 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.263 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.263 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.263 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.263 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.264 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.264 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.264 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.264 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.264 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.265 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.265 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.265 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.265 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.265 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.266 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.266 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.266 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.266 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.267 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.267 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.268 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.268 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.268 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.268 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.268 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.268 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.269 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.269 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.269 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.269 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.270 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.270 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.270 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.270 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.271 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.271 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.271 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.271 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.272 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.272 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.272 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.272 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.272 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.272 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.273 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.273 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.273 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.273 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.273 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.273 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.274 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.274 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.274 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.274 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.274 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.274 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.275 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.275 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.275 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.275 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.275 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.275 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.276 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.276 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.276 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.276 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.276 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.277 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.277 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.277 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.277 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.277 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.278 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.278 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.278 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.278 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.278 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.278 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.279 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.279 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.279 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.279 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.279 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.279 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.280 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.280 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.280 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.280 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.280 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.280 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.281 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.281 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.281 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.281 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.281 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.281 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.282 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.282 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.282 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.282 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.282 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.283 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.283 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.283 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.283 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.283 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.284 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.284 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.284 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.284 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.284 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.284 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.285 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.285 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.285 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.285 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.285 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.285 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.286 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.286 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.287 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.287 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.287 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.287 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.289 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.289 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.290 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.290 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.290 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.290 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.291 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.291 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.291 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.291 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.291 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.291 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.292 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.292 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.292 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.292 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.292 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.293 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.293 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.293 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.293 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.293 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.294 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.294 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.294 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.294 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.294 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.294 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.295 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.295 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.295 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.295 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.295 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.295 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.296 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.296 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.296 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.296 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.297 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.297 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.297 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.297 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.297 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.298 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.298 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.298 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.298 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.298 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.298 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.298 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.299 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.299 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.299 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.299 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.300 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.300 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.300 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.300 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.301 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.301 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.301 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.301 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.301 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.301 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.302 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.302 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.302 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.302 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.304 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.304 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.305 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.305 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.306 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.306 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.306 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.306 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.308 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.309 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.309 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.309 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.310 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.310 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.310 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.310 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.310 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.311 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.311 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.311 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.311 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.311 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.312 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.312 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.312 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.312 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.312 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.312 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.313 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.313 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.314 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.314 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.314 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.314 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.314 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.314 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.315 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.315 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.315 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.315 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.315 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.316 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.316 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.316 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.316 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.316 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.316 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.317 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.317 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 39, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)] in 115 ms
14/08/06 13:10:10.317 DEBUG ConnectionManager: Received [BufferMessage(id = 39, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.317 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:10:10.317 DEBUG ConnectionManager: Handling [BufferMessage(id = 39, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.317 DEBUG ConnectionManager: Calling back
14/08/06 13:10:10.317 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 40, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)] in 114 ms
14/08/06 13:10:10.317 DEBUG SendingConnection: Finished sending [BufferMessage(id = 39, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)] in 116 ms
14/08/06 13:10:10.318 DEBUG ConnectionManager: Received [BufferMessage(id = 40, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.318 DEBUG ConnectionManager: Before Sending [BufferAckMessage(aid = 39, id = 49, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)] connectionid: joshs-mbp_58978_2
14/08/06 13:10:10.318 DEBUG ConnectionManager: Sending [BufferAckMessage(aid = 39, id = 49, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.318 DEBUG SendingConnection: Finished sending [BufferMessage(id = 40, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)] in 117 ms
14/08/06 13:10:10.318 DEBUG SendingConnection: Added [BufferAckMessage(aid = 39, id = 49, size = 0)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.318 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:10:10.318 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.318 DEBUG SendingConnection: Finished sending [BufferMessage(id = 41, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)] in 117 ms
14/08/06 13:10:10.318 DEBUG ConnectionManager: Handling [BufferMessage(id = 40, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.318 DEBUG ConnectionManager: Calling back
14/08/06 13:10:10.318 INFO SendingConnection: Initiating connection to [joshs-mbp/192.168.1.245:58977]
14/08/06 13:10:10.318 DEBUG ConnectionManager: Handling delay is 1 ms
14/08/06 13:10:10.318 DEBUG ConnectionManager: Before Sending [BufferAckMessage(aid = 40, id = 50, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)] connectionid: joshs-mbp_58978_2
14/08/06 13:10:10.318 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 41, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)] in 113 ms
14/08/06 13:10:10.318 DEBUG ConnectionManager: Sending [BufferAckMessage(aid = 40, id = 50, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.318 DEBUG SendingConnection: Finished sending [BufferMessage(id = 42, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)] in 117 ms
14/08/06 13:10:10.318 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.318 DEBUG SendingConnection: Finished sending [BufferMessage(id = 43, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)] in 116 ms
14/08/06 13:10:10.318 INFO SendingConnection: Connected to [joshs-mbp/192.168.1.245:58977], 2 messages pending
14/08/06 13:10:10.318 DEBUG SendingConnection: Added [BufferAckMessage(aid = 40, id = 50, size = 0)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.319 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.319 DEBUG SendingConnection: Starting to send [BufferAckMessage(aid = 39, id = 49, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.318 DEBUG ConnectionManager: Received [BufferMessage(id = 41, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.319 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.319 DEBUG ConnectionManager: Handling delay is 1 ms
14/08/06 13:10:10.319 DEBUG SendingConnection: Finished sending [BufferMessage(id = 44, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)] in 117 ms
14/08/06 13:10:10.318 INFO ConnectionManager: Accepted connection from [joshs-mbp/192.168.1.245]
14/08/06 13:10:10.319 DEBUG SendingConnection: Starting to send [BufferAckMessage(aid = 40, id = 50, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.319 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:10:10.319 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.319 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 42, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)] in 113 ms
14/08/06 13:10:10.319 DEBUG ConnectionManager: Handling [BufferMessage(id = 41, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.319 DEBUG SendingConnection: Finished sending [BufferAckMessage(aid = 39, id = 49, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)] in 0 ms
14/08/06 13:10:10.319 DEBUG ConnectionManager: Calling back
14/08/06 13:10:10.319 DEBUG SendingConnection: Finished sending [BufferAckMessage(aid = 40, id = 50, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)] in 0 ms
14/08/06 13:10:10.319 DEBUG ConnectionManager: Before Sending [BufferAckMessage(aid = 41, id = 51, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)] connectionid: joshs-mbp_58978_2
14/08/06 13:10:10.319 DEBUG ConnectionManager: Received [BufferMessage(id = 42, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.319 DEBUG ConnectionManager: Received [BufferAckMessage(aid = 39, id = 49, size = 0)] from [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.319 DEBUG ConnectionManager: Sending [BufferAckMessage(aid = 41, id = 51, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.319 DEBUG SendingConnection: Added [BufferAckMessage(aid = 41, id = 51, size = 0)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.319 DEBUG ConnectionManager: Handling delay is 0 ms
14/08/06 13:10:10.319 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.320 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.320 DEBUG ConnectionManager: Handler thread delay is 1 ms
14/08/06 13:10:10.320 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.320 DEBUG ConnectionManager: Handler thread delay is 1 ms
14/08/06 13:10:10.320 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.320 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 43, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)] in 113 ms
14/08/06 13:10:10.320 DEBUG ConnectionManager: Handling [BufferAckMessage(aid = 39, id = 49, size = 0)] from [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.320 DEBUG SendingConnection: Starting to send [BufferAckMessage(aid = 41, id = 51, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.320 DEBUG ConnectionManager: Handling [BufferMessage(id = 42, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.320 DEBUG ConnectionManager: Received [BufferMessage(id = 43, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.320 DEBUG ConnectionManager: Calling back
14/08/06 13:10:10.320 DEBUG ConnectionManager: Handling delay is 1 ms
14/08/06 13:10:10.320 DEBUG ConnectionManager: Received [BufferAckMessage(aid = 40, id = 50, size = 0)] from [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.320 DEBUG ConnectionManager: Before Sending [BufferAckMessage(aid = 42, id = 52, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)] connectionid: joshs-mbp_58978_2
14/08/06 13:10:10.320 DEBUG SendingConnection: Finished sending [BufferAckMessage(aid = 41, id = 51, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)] in 0 ms
14/08/06 13:10:10.320 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:10:10.320 DEBUG ConnectionManager: Sending [BufferAckMessage(aid = 42, id = 52, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.320 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 44, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)] in 112 ms
14/08/06 13:10:10.320 DEBUG SendingConnection: Added [BufferAckMessage(aid = 42, id = 52, size = 0)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.320 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:10:10.320 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.320 DEBUG ConnectionManager: Handling [BufferMessage(id = 43, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.320 DEBUG ConnectionManager: Handling [BufferAckMessage(aid = 40, id = 50, size = 0)] from [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.320 DEBUG ConnectionManager: Handling delay is 1 ms
14/08/06 13:10:10.320 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.321 DEBUG ConnectionManager: Handling delay is 1 ms
14/08/06 13:10:10.321 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.320 DEBUG ConnectionManager: Calling back
14/08/06 13:10:10.321 DEBUG ConnectionManager: Received [BufferMessage(id = 44, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.321 DEBUG SendingConnection: Starting to send [BufferAckMessage(aid = 42, id = 52, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.321 DEBUG ConnectionManager: Received [BufferAckMessage(aid = 41, id = 51, size = 0)] from [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.321 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.321 DEBUG SendingConnection: Finished sending [BufferAckMessage(aid = 42, id = 52, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)] in 0 ms
14/08/06 13:10:10.321 DEBUG ConnectionManager: Before Sending [BufferAckMessage(aid = 43, id = 53, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)] connectionid: joshs-mbp_58978_2
14/08/06 13:10:10.321 DEBUG ConnectionManager: Sending [BufferAckMessage(aid = 43, id = 53, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.321 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:10:10.321 DEBUG SendingConnection: Added [BufferAckMessage(aid = 43, id = 53, size = 0)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.321 DEBUG ConnectionManager: Handling [BufferMessage(id = 44, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.321 DEBUG ConnectionManager: Handling delay is 1 ms
14/08/06 13:10:10.321 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 45, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)] in 112 ms
14/08/06 13:10:10.321 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:10:10.321 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.321 DEBUG SendingConnection: Finished sending [BufferMessage(id = 45, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)] in 119 ms
14/08/06 13:10:10.321 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.321 DEBUG ConnectionManager: Calling back
14/08/06 13:10:10.321 DEBUG SendingConnection: Finished sending [BufferMessage(id = 46, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)] in 113 ms
14/08/06 13:10:10.321 DEBUG ConnectionManager: Handling [BufferAckMessage(aid = 41, id = 51, size = 0)] from [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.322 DEBUG SendingConnection: Finished sending [BufferMessage(id = 47, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)] in 114 ms
14/08/06 13:10:10.322 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.322 DEBUG SendingConnection: Finished sending [BufferMessage(id = 48, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58978)] in 112 ms
14/08/06 13:10:10.322 DEBUG ConnectionManager: Before Sending [BufferAckMessage(aid = 44, id = 54, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)] connectionid: joshs-mbp_58978_2
14/08/06 13:10:10.322 DEBUG ConnectionManager: Received [BufferMessage(id = 45, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.322 DEBUG ConnectionManager: Received [BufferAckMessage(aid = 42, id = 52, size = 0)] from [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.322 DEBUG ConnectionManager: Sending [BufferAckMessage(aid = 44, id = 54, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.322 DEBUG ConnectionManager: Handling delay is 1 ms
14/08/06 13:10:10.322 DEBUG SendingConnection: Starting to send [BufferAckMessage(aid = 43, id = 53, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.322 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:10:10.322 DEBUG SendingConnection: Finished sending [BufferAckMessage(aid = 43, id = 53, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)] in 0 ms
14/08/06 13:10:10.322 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 46, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)] in 112 ms
14/08/06 13:10:10.322 DEBUG SendingConnection: Added [BufferAckMessage(aid = 44, id = 54, size = 0)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.322 DEBUG ConnectionManager: Handling [BufferMessage(id = 45, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.322 DEBUG ConnectionManager: Calling back
14/08/06 13:10:10.322 DEBUG ConnectionManager: Handling delay is 1 ms
14/08/06 13:10:10.322 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:10:10.322 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.322 DEBUG ConnectionManager: Handling [BufferAckMessage(aid = 42, id = 52, size = 0)] from [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.322 DEBUG ConnectionManager: Received [BufferMessage(id = 46, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.322 DEBUG ConnectionManager: Before Sending [BufferAckMessage(aid = 45, id = 55, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)] connectionid: joshs-mbp_58978_2
14/08/06 13:10:10.322 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.322 DEBUG ConnectionManager: Sending [BufferAckMessage(aid = 45, id = 55, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.323 DEBUG SendingConnection: Added [BufferAckMessage(aid = 45, id = 55, size = 0)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.323 DEBUG ConnectionManager: Handling delay is 1 ms
14/08/06 13:10:10.322 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.323 DEBUG ConnectionManager: Handler thread delay is 1 ms
14/08/06 13:10:10.323 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 47, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)] in 111 ms
14/08/06 13:10:10.322 DEBUG ConnectionManager: Handling delay is 0 ms
14/08/06 13:10:10.323 DEBUG ConnectionManager: Handling [BufferMessage(id = 46, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.323 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.323 DEBUG ConnectionManager: Received [BufferMessage(id = 47, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.323 DEBUG ConnectionManager: Received [BufferAckMessage(aid = 43, id = 53, size = 0)] from [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.323 DEBUG SendingConnection: Starting to send [BufferAckMessage(aid = 44, id = 54, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.323 DEBUG ConnectionManager: Calling back
14/08/06 13:10:10.323 DEBUG SendingConnection: Starting to send [BufferAckMessage(aid = 45, id = 55, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.323 DEBUG ConnectionManager: Before Sending [BufferAckMessage(aid = 46, id = 56, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)] connectionid: joshs-mbp_58978_2
14/08/06 13:10:10.323 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 48, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)] in 110 ms
14/08/06 13:10:10.323 DEBUG ConnectionManager: Sending [BufferAckMessage(aid = 46, id = 56, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.323 DEBUG SendingConnection: Finished sending [BufferAckMessage(aid = 44, id = 54, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)] in 0 ms
14/08/06 13:10:10.323 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:10:10.323 DEBUG SendingConnection: Finished sending [BufferAckMessage(aid = 45, id = 55, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)] in 0 ms
14/08/06 13:10:10.323 DEBUG ConnectionManager: Received [BufferMessage(id = 48, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.323 DEBUG ConnectionManager: Handling [BufferMessage(id = 47, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.323 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.323 DEBUG ConnectionManager: Calling back
14/08/06 13:10:10.324 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.323 DEBUG SendingConnection: Added [BufferAckMessage(aid = 46, id = 56, size = 0)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.323 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:10:10.324 DEBUG ConnectionManager: Handler thread delay is 1 ms
14/08/06 13:10:10.324 DEBUG ConnectionManager: Handling delay is 2 ms
14/08/06 13:10:10.324 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.324 DEBUG ConnectionManager: Before Sending [BufferAckMessage(aid = 47, id = 57, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)] connectionid: joshs-mbp_58978_2
14/08/06 13:10:10.324 DEBUG ConnectionManager: Received [BufferAckMessage(aid = 44, id = 54, size = 0)] from [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.324 DEBUG ConnectionManager: Handling [BufferMessage(id = 48, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.324 DEBUG SendingConnection: Starting to send [BufferAckMessage(aid = 46, id = 56, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.324 DEBUG ConnectionManager: Handling [BufferAckMessage(aid = 43, id = 53, size = 0)] from [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.324 DEBUG ConnectionManager: Calling back
14/08/06 13:10:10.324 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.324 DEBUG ConnectionManager: Sending [BufferAckMessage(aid = 47, id = 57, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.324 DEBUG ConnectionManager: Before Sending [BufferAckMessage(aid = 48, id = 58, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)] connectionid: joshs-mbp_58978_2
14/08/06 13:10:10.324 DEBUG ConnectionManager: Handling delay is 1 ms
14/08/06 13:10:10.324 DEBUG SendingConnection: Finished sending [BufferAckMessage(aid = 46, id = 56, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)] in 0 ms
14/08/06 13:10:10.324 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:10:10.324 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.324 DEBUG SendingConnection: Added [BufferAckMessage(aid = 47, id = 57, size = 0)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.324 DEBUG ConnectionManager: Handling [BufferAckMessage(aid = 44, id = 54, size = 0)] from [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.324 DEBUG ConnectionManager: Sending [BufferAckMessage(aid = 48, id = 58, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.324 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.324 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.324 DEBUG ConnectionManager: Handling delay is 1 ms
14/08/06 13:10:10.324 DEBUG SendingConnection: Added [BufferAckMessage(aid = 48, id = 58, size = 0)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.324 DEBUG ConnectionManager: Handling delay is 0 ms
14/08/06 13:10:10.325 DEBUG SendingConnection: Starting to send [BufferAckMessage(aid = 47, id = 57, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.325 DEBUG ConnectionManager: Handling delay is 2 ms
14/08/06 13:10:10.325 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.325 DEBUG ConnectionManager: Received [BufferAckMessage(aid = 45, id = 55, size = 0)] from [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.325 DEBUG SendingConnection: Starting to send [BufferAckMessage(aid = 48, id = 58, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)]
14/08/06 13:10:10.325 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.325 DEBUG SendingConnection: Finished sending [BufferAckMessage(aid = 47, id = 57, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)] in 0 ms
14/08/06 13:10:10.325 DEBUG SendingConnection: Finished sending [BufferAckMessage(aid = 48, id = 58, size = 0)] to [ConnectionManagerId(joshs-mbp,58977)] in 0 ms
14/08/06 13:10:10.325 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.325 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:10:10.325 DEBUG ConnectionManager: Handling [BufferAckMessage(aid = 45, id = 55, size = 0)] from [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.325 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.325 DEBUG ConnectionManager: Handling delay is 0 ms
14/08/06 13:10:10.325 DEBUG ConnectionManager: Received [BufferAckMessage(aid = 46, id = 56, size = 0)] from [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.325 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.325 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:10:10.325 DEBUG ConnectionManager: Handling [BufferAckMessage(aid = 46, id = 56, size = 0)] from [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.326 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.326 DEBUG ConnectionManager: Handling delay is 1 ms
14/08/06 13:10:10.326 DEBUG ConnectionManager: Received [BufferAckMessage(aid = 47, id = 57, size = 0)] from [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.326 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.326 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:10:10.326 DEBUG ConnectionManager: Handling [BufferAckMessage(aid = 47, id = 57, size = 0)] from [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.326 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.326 DEBUG ConnectionManager: Handling delay is 0 ms
14/08/06 13:10:10.326 DEBUG ConnectionManager: Received [BufferAckMessage(aid = 48, id = 58, size = 0)] from [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.326 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.326 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:10:10.326 DEBUG ConnectionManager: Handling [BufferAckMessage(aid = 48, id = 58, size = 0)] from [ConnectionManagerId(joshs-mbp,58978)]
14/08/06 13:10:10.326 DEBUG ConnectionManager: Handling delay is 0 ms
14/08/06 13:10:10.326 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.326 INFO ConnectionManager: Selector thread was interrupted!
14/08/06 13:10:10.327 INFO ConnectionManager: Removing ReceivingConnection to ConnectionManagerId(joshs-mbp,58978)
14/08/06 13:10:10.327 INFO ConnectionManager: Key not valid ? sun.nio.ch.SelectionKeyImpl@680ccd22
14/08/06 13:10:10.327 INFO ConnectionManager: Removing SendingConnection to ConnectionManagerId(joshs-mbp,58978)
14/08/06 13:10:10.327 INFO ConnectionManager: Removing SendingConnection to ConnectionManagerId(joshs-mbp,58977)
14/08/06 13:10:10.327 INFO ConnectionManager: key already cancelled ? sun.nio.ch.SelectionKeyImpl@680ccd22
java.nio.channels.CancelledKeyException
at org.apache.spark.network.ConnectionManager.run(ConnectionManager.scala:366)
at org.apache.spark.network.ConnectionManager$$anon$4.run(ConnectionManager.scala:119)
14/08/06 13:10:10.327 INFO ConnectionManager: Removing SendingConnection to ConnectionManagerId(joshs-mbp,58978)
14/08/06 13:10:10.327 DEBUG ConnectionManager: Ignoring exception
java.nio.channels.CancelledKeyException
at sun.nio.ch.SelectionKeyImpl.ensureValid(SelectionKeyImpl.java:73)
at sun.nio.ch.SelectionKeyImpl.interestOps(SelectionKeyImpl.java:82)
at org.apache.spark.network.ConnectionManager.triggerForceCloseByException(ConnectionManager.scala:223)
at org.apache.spark.network.ConnectionManager.run(ConnectionManager.scala:373)
at org.apache.spark.network.ConnectionManager$$anon$4.run(ConnectionManager.scala:119)
14/08/06 13:10:10.327 WARN ConnectionManager: All connections not cleaned up
14/08/06 13:10:10.327 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.327 INFO ConnectionManager: Removing ReceivingConnection to ConnectionManagerId(joshs-mbp,58977)
14/08/06 13:10:10.328 ERROR ConnectionManager: Corresponding SendingConnectionManagerId not found
14/08/06 13:10:10.328 INFO ConnectionManager: ConnectionManager stopped
14/08/06 13:10:10.328 DEBUG ConnectionManager: Selector selected 0 of 1 keys
14/08/06 13:10:10.328 INFO ConnectionManager: Selector thread was interrupted!
14/08/06 13:10:10.328 INFO ConnectionManager: ConnectionManager stopped
14/08/06 13:10:10.329 INFO SecurityManager: Changing view acls to: joshrosen
14/08/06 13:10:10.330 INFO SecurityManager: SecurityManager: authentication disabled; ui acls disabled; users with view permissions: Set(joshrosen)
14/08/06 13:10:10.330 INFO ConnectionManager: Bound socket to port 58981 with id = ConnectionManagerId(joshs-mbp,58981)
14/08/06 13:10:10.330 INFO ConnectionManager: Bound socket to port 58982 with id = ConnectionManagerId(joshs-mbp,58982)
14/08/06 13:10:10.412 DEBUG ConnectionManager: Before Sending [BufferMessage(id = 59, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58982)] connectionid: joshs-mbp_58981_1
14/08/06 13:10:10.412 DEBUG ConnectionManager: Sending [BufferMessage(id = 59, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58982)]
14/08/06 13:10:10.412 DEBUG SendingConnection: Added [BufferMessage(id = 59, size = 10485760)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58982)]
14/08/06 13:10:10.412 DEBUG ConnectionManager: Selector selected 0 of 1 keys
14/08/06 13:10:10.412 INFO SendingConnection: Initiating connection to [joshs-mbp/192.168.1.245:58982]
14/08/06 13:10:10.412 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.413 INFO SendingConnection: Connected to [joshs-mbp/192.168.1.245:58982], 1 messages pending
14/08/06 13:10:10.413 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.413 INFO ConnectionManager: Accepted connection from [joshs-mbp/192.168.1.245]
14/08/06 13:10:10.413 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.413 DEBUG SendingConnection: Starting to send [BufferMessage(id = 59, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58982)]
14/08/06 13:10:10.414 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.417 DEBUG ReceivingConnection: Starting to receive [BufferMessage(id = 59, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58981)]
14/08/06 13:10:10.417 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.417 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.417 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.418 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.418 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.418 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.419 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.419 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.420 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.420 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.420 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.420 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.421 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.421 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.421 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.421 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.421 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.422 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.422 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.422 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.422 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.422 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.422 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.423 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.423 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.423 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.423 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.423 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.423 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.424 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.424 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.424 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.424 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.424 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.424 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.424 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.425 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.425 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.425 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.425 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.425 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.425 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.426 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.426 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.426 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.426 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.426 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.426 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.426 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.426 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.427 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.427 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.427 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.427 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.427 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.427 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.427 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.427 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.428 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.428 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.428 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.428 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.428 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.428 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.428 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.428 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.428 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.429 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.429 DEBUG SendingConnection: Finished sending [BufferMessage(id = 59, size = 10485760)] to [ConnectionManagerId(joshs-mbp,58982)] in 16 ms
14/08/06 13:10:10.429 DEBUG ReceivingConnection: Finished receiving [BufferMessage(id = 59, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58981)] in 12 ms
14/08/06 13:10:10.429 DEBUG ConnectionManager: Received [BufferMessage(id = 59, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58981)]
14/08/06 13:10:10.429 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.429 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:10:10.429 DEBUG ConnectionManager: Handling [BufferMessage(id = 59, size = 10485760)] from [ConnectionManagerId(joshs-mbp,58981)]
14/08/06 13:10:10.429 DEBUG ConnectionManager: Calling back
14/08/06 13:10:10.431 ERROR ConnectionManager: Exception was thrown during processing message
java.lang.Exception
at org.apache.spark.network.ConnectionManagerSuite$$anonfun$6$$anonfun$apply$mcV$sp$15.apply(ConnectionManagerSuite.scala:233)
at org.apache.spark.network.ConnectionManagerSuite$$anonfun$6$$anonfun$apply$mcV$sp$15.apply(ConnectionManagerSuite.scala:232)
at org.apache.spark.network.ConnectionManager.org$apache$spark$network$ConnectionManager$$handleMessage(ConnectionManager.scala:667)
at org.apache.spark.network.ConnectionManager$$anon$9.run(ConnectionManager.scala:508)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
14/08/06 13:10:10.431 DEBUG ConnectionManager: Before Sending [BufferAckMessage(aid = 59, id = 60, size = 0)] to [ConnectionManagerId(joshs-mbp,58981)] connectionid: joshs-mbp_58982_2
14/08/06 13:10:10.431 DEBUG ConnectionManager: Sending [BufferAckMessage(aid = 59, id = 60, size = 0)] to [ConnectionManagerId(joshs-mbp,58981)]
14/08/06 13:10:10.431 DEBUG SendingConnection: Added [BufferAckMessage(aid = 59, id = 60, size = 0)] to outbox for sending to [ConnectionManagerId(joshs-mbp,58981)]
14/08/06 13:10:10.432 DEBUG ConnectionManager: Handling delay is 3 ms
14/08/06 13:10:10.432 DEBUG ConnectionManager: Selector selected 0 of 2 keys
14/08/06 13:10:10.432 INFO SendingConnection: Initiating connection to [joshs-mbp/192.168.1.245:58981]
14/08/06 13:10:10.432 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.432 INFO ConnectionManager: Accepted connection from [joshs-mbp/192.168.1.245]
14/08/06 13:10:10.432 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.432 INFO SendingConnection: Connected to [joshs-mbp/192.168.1.245:58981], 1 messages pending
14/08/06 13:10:10.432 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.432 DEBUG SendingConnection: Starting to send [BufferAckMessage(aid = 59, id = 60, size = 0)] to [ConnectionManagerId(joshs-mbp,58981)]
14/08/06 13:10:10.432 DEBUG SendingConnection: Finished sending [BufferAckMessage(aid = 59, id = 60, size = 0)] to [ConnectionManagerId(joshs-mbp,58981)] in 0 ms
14/08/06 13:10:10.433 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.433 DEBUG ConnectionManager: Received [BufferAckMessage(aid = 59, id = 60, size = 0)] from [ConnectionManagerId(joshs-mbp,58982)]
14/08/06 13:10:10.433 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.433 DEBUG ConnectionManager: Handler thread delay is 0 ms
14/08/06 13:10:10.433 DEBUG ConnectionManager: Handling [BufferAckMessage(aid = 59, id = 60, size = 0)] from [ConnectionManagerId(joshs-mbp,58982)]
14/08/06 13:10:10.433 DEBUG ConnectionManager: Handling delay is 0 ms
14/08/06 13:10:10.433 DEBUG ConnectionManager: Selector selected 0 of 3 keys
14/08/06 13:10:10.433 INFO ConnectionManager: Selector thread was interrupted!
14/08/06 13:10:10.433 INFO ConnectionManager: Removing ReceivingConnection to ConnectionManagerId(joshs-mbp,58982)
14/08/06 13:10:10.434 INFO ConnectionManager: Removing SendingConnection to ConnectionManagerId(joshs-mbp,58982)
14/08/06 13:10:10.434 INFO ConnectionManager: Key not valid ? sun.nio.ch.SelectionKeyImpl@4c05279b
14/08/06 13:10:10.434 INFO ConnectionManager: Removing SendingConnection to ConnectionManagerId(joshs-mbp,58981)
14/08/06 13:10:10.434 INFO ConnectionManager: Removing SendingConnection to ConnectionManagerId(joshs-mbp,58982)
14/08/06 13:10:10.434 INFO ConnectionManager: Removing ReceivingConnection to ConnectionManagerId(joshs-mbp,58981)
14/08/06 13:10:10.434 INFO ConnectionManager: key already cancelled ? sun.nio.ch.SelectionKeyImpl@4c05279b
java.nio.channels.CancelledKeyException
at org.apache.spark.network.ConnectionManager.run(ConnectionManager.scala:366)
at org.apache.spark.network.ConnectionManager$$anon$4.run(ConnectionManager.scala:119)
14/08/06 13:10:10.434 ERROR ConnectionManager: Corresponding SendingConnectionManagerId not found
14/08/06 13:10:10.434 WARN ConnectionManager: All connections not cleaned up
14/08/06 13:10:10.434 DEBUG ConnectionManager: Ignoring exception
java.nio.channels.CancelledKeyException
at sun.nio.ch.SelectionKeyImpl.ensureValid(SelectionKeyImpl.java:73)
at sun.nio.ch.SelectionKeyImpl.interestOps(SelectionKeyImpl.java:82)
at org.apache.spark.network.ConnectionManager.triggerForceCloseByException(ConnectionManager.scala:223)
at org.apache.spark.network.ConnectionManager.run(ConnectionManager.scala:373)
at org.apache.spark.network.ConnectionManager$$anon$4.run(ConnectionManager.scala:119)
14/08/06 13:10:10.434 INFO ConnectionManager: ConnectionManager stopped
14/08/06 13:10:10.435 INFO ConnectionManager: Key not valid ? sun.nio.ch.SelectionKeyImpl@3d5807d
14/08/06 13:10:10.435 INFO ConnectionManager: key already cancelled ? sun.nio.ch.SelectionKeyImpl@3d5807d
java.nio.channels.CancelledKeyException
at org.apache.spark.network.ConnectionManager.run(ConnectionManager.scala:290)
at org.apache.spark.network.ConnectionManager$$anon$4.run(ConnectionManager.scala:119)
14/08/06 13:10:10.435 DEBUG ConnectionManager: Ignoring exception
java.nio.channels.CancelledKeyException
at sun.nio.ch.SelectionKeyImpl.ensureValid(SelectionKeyImpl.java:73)
at sun.nio.ch.SelectionKeyImpl.interestOps(SelectionKeyImpl.java:82)
at org.apache.spark.network.ConnectionManager.triggerForceCloseByException(ConnectionManager.scala:223)
at org.apache.spark.network.ConnectionManager.run(ConnectionManager.scala:295)
at org.apache.spark.network.ConnectionManager$$anon$4.run(ConnectionManager.scala:119)
14/08/06 13:10:10.435 DEBUG ConnectionManager: Selector selected 0 of 1 keys
14/08/06 13:10:10.435 INFO ConnectionManager: Selector thread was interrupted!
14/08/06 13:10:10.435 INFO ConnectionManager: ConnectionManager stopped
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment