Skip to content

Instantly share code, notes, and snippets.

@agarajh
Last active December 28, 2015 02:38
Show Gist options
  • Save agarajh/57e20c55098aaad3d595 to your computer and use it in GitHub Desktop.
Save agarajh/57e20c55098aaad3d595 to your computer and use it in GitHub Desktop.
Push Notifications are not being resent after I/O problems are resolved.
2013-11-12 11:11:31,173 | drivexone-push | INFO | ContextLoader
|Root WebApplicationContext: initialization started
2013-11-12 11:11:49,883 | drivexone-push | DEBUG | PlatformDependent
|-Dio.netty.noPreferDirect: false
2013-11-12 11:11:49,948 | drivexone-push | DEBUG | NioEventLoop
|-Dio.netty.noKeySetOptimization: false
2013-11-12 11:11:49,949 | drivexone-push | DEBUG | NioEventLoop
|-Dio.netty.selectorAutoRebuildThreshold: 512
2013-11-12 11:11:49,966 | drivexone-push | TRACE | NioEventLoop
|Instrumented an optimized java.util.Set into: sun.nio.ch.WindowsSelectorImpl@48e3424f
2013-11-12 11:11:50,014 | drivexone-push | TRACE | NioEventLoop
|Instrumented an optimized java.util.Set into: sun.nio.ch.WindowsSelectorImpl@152ff879
2013-11-12 11:11:50,015 | drivexone-push | TRACE | NioEventLoop
|Instrumented an optimized java.util.Set into: sun.nio.ch.WindowsSelectorImpl@61dacd
2013-11-12 11:11:50,020 | drivexone-push | TRACE | NioEventLoop
|Instrumented an optimized java.util.Set into: sun.nio.ch.WindowsSelectorImpl@5220dbfe
2013-11-12 11:11:50,023 | drivexone-push | INFO | ApplePushManager
|DEBUGGING
2013-11-12 11:11:50,029 | drivexone-push | DEBUG | ApnsClientThread
|ApnsClientThread-1 beginning connection process.
2013-11-12 11:11:50,046 | drivexone-push | DEBUG | ApnsClientThread
|ApnsClientThread-3 beginning connection process.
2013-11-12 11:11:50,046 | drivexone-push | DEBUG | ApnsClientThread
|ApnsClientThread-2 beginning connection process.
2013-11-12 11:11:50,532 | drivexone-push | ERROR | ApnsClientThread
|ApnsClientThread-1 failed to connect to APNs gateway.
java.nio.channels.UnresolvedAddressException: null
at sun.nio.ch.Net.checkAddress(Net.java:85) ~[na:1.7.0_21]
at sun.nio.ch.SocketChannelImpl.connect(SocketChannelImpl.java:597) ~[na:1.7.0_21]
at io.netty.channel.socket.nio.NioSocketChannel.doConnect(NioSocketChannel.java:195) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.channel.nio.AbstractNioChannel$AbstractNioUnsafe.connect(AbstractNioChannel.java:172) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.channel.DefaultChannelPipeline$HeadHandler.connect(DefaultChannelPipeline.java:1008) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.channel.DefaultChannelHandlerContext.invokeConnect(DefaultChannelHandlerContext.java:491) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.channel.DefaultChannelHandlerContext.connect(DefaultChannelHandlerContext.java:476) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.handler.ssl.SslHandler.connect(SslHandler.java:370) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.channel.DefaultChannelHandlerContext.invokeConnect(DefaultChannelHandlerContext.java:491) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.channel.DefaultChannelHandlerContext.connect(DefaultChannelHandlerContext.java:476) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.channel.ChannelOutboundHandlerAdapter.connect(ChannelOutboundHandlerAdapter.java:47) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.channel.DefaultChannelHandlerContext.invokeConnect(DefaultChannelHandlerContext.java:491) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.channel.DefaultChannelHandlerContext.connect(DefaultChannelHandlerContext.java:476) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.channel.DefaultChannelHandlerContext.connect(DefaultChannelHandlerContext.java:461) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.channel.DefaultChannelPipeline.connect(DefaultChannelPipeline.java:847) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.channel.AbstractChannel.connect(AbstractChannel.java:198) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.bootstrap.Bootstrap$2.run(Bootstrap.java:165) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:354) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:366) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.util.concurrent.SingleThreadEventExecutor$2.run(SingleThreadEventExecutor.java:101) ~[netty-all-4.0.7.Final.jar:na]
at java.lang.Thread.run(Thread.java:722) ~[na:1.7.0_21]
2013-11-12 11:11:51,733 | drivexone-push | DEBUG | ApnsClientThread
|ApnsClientThread-1 beginning connection process.
2013-11-12 11:12:01,234 | drivexone-push | DEBUG | ApnsClientThread
|ApnsClientThread-3 connected.
2013-11-12 11:12:01,234 | drivexone-push | DEBUG | ApnsClientThread
|ApnsClientThread-3 waiting for TLS handshake.
2013-11-12 11:12:01,235 | drivexone-push | DEBUG | ApnsClientThread
|ApnsClientThread-1 connected.
2013-11-12 11:12:01,235 | drivexone-push | DEBUG | ApnsClientThread
|ApnsClientThread-1 waiting for TLS handshake.
2013-11-12 11:12:01,246 | drivexone-push | DEBUG | ResourceLeakDetector
|-Dio.netty.noResourceLeakDetection: false
2013-11-12 11:12:01,252 | drivexone-push | DEBUG | ApnsClientThread
|ApnsClientThread-2 connected.
2013-11-12 11:12:01,252 | drivexone-push | DEBUG | ApnsClientThread
|ApnsClientThread-2 waiting for TLS handshake.
2013-11-12 11:12:01,760 | drivexone-push | DEBUG | ApnsClientThread
|ApnsClientThread-2 successfully completed TLS handshake.
2013-11-12 11:12:01,761 | drivexone-push | TRACE | ApnsClientThread
|ApnsClientThread-2 sending SendableApnsPushNotification [sequenceNumber=0, token=3f3ad78b00c5e7f740dd06fa5357333e0d3140d640b4b08c068d4927f0790f91, payload={"aps":{"alert":{"loc-args":["MILE_CRUNCHETE3"],"loc-key":"VVC_BADGE"}}}, deliveryInvalidation=null]
2013-11-12 11:12:01,784 | drivexone-push | DEBUG | ApnsClientThread
|ApnsClientThread-1 successfully completed TLS handshake.
2013-11-12 11:12:01,823 | drivexone-push | TRACE | ApnsClientThread
|ApnsClientThread-2 successfully wrote notification 0
2013-11-12 11:12:01,924 | drivexone-push | DEBUG | ApnsClientThread
|ApnsClientThread-3 successfully completed TLS handshake.
2013-11-12 11:12:02,007 | drivexone-push | DEBUG | ApnsClientThread
|ApnsClientThread-2 waiting for connection to close.
2013-11-12 11:12:02,008 | drivexone-push | DEBUG | ApnsClientThread
|ApnsClientThread-2 beginning connection process.
2013-11-12 11:12:02,028 | drivexone-push | INFO | PushNotificationController
|SimpleApnsPushNotification [token=3f3ad78b00c5e7f740dd06fa5357333e0d3140d640b4b08c068d4927f0790f91, payload={"aps":{"alert":{"loc-args":["MILE_CRUNCHETE3"],"loc-key":"VVC_BADGE"}}}, expiration=null] was rejected with rejection reason:INVALID_TOKEN
2013-11-12 11:12:02,028 | drivexone-push | INFO | PushNotificationController
|Calling endpoint to delete the token from the internal database
2013-11-12 11:12:02,029 | drivexone-push | INFO | RestfulClientManager
|The old Registration Id will be deleted from the internal database
2013-11-12 11:12:02,177 | drivexone-push | DEBUG | ApnsClientThread
|ApnsClientThread-2 connected.
2013-11-12 11:12:02,177 | drivexone-push | DEBUG | ApnsClientThread
|ApnsClientThread-2 waiting for TLS handshake.
2013-11-12 11:12:02,690 | drivexone-push | DEBUG | ApnsClientThread
|ApnsClientThread-2 successfully completed TLS handshake.
2013-11-12 11:12:28,300 | drivexone-push | TRACE | DispatcherServlet
|Bound request context to thread: org.apache.catalina.connector.RequestFacade@24d6fbe7
2013-11-12 11:12:28,301 | drivexone-push | DEBUG | DispatcherServlet
|DispatcherServlet with name 'appServlet' processing POST request for [/vvc-push/push]
2013-11-12 11:12:28,303 | drivexone-push | TRACE | DispatcherServlet
|Testing handler map [org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping@2393ad40] in DispatcherServlet with name 'appServlet'
2013-11-12 11:12:28,303 | drivexone-push | DEBUG | RequestMappingHandlerMapping
|Looking up handler method for path /push
2013-11-12 11:12:28,304 | drivexone-push | TRACE | RequestMappingHandlerMapping
|Found 1 matching mapping(s) for [/push] : [{[/push],methods=[POST],params=[],headers=[],consumes=[application/json;charset=utf-8],produces=[application/json;charset=utf-8],custom=[]}]
2013-11-12 11:12:28,305 | drivexone-push | DEBUG | RequestMappingHandlerMapping
|Returning handler method [public org.springframework.http.ResponseEntity<java.lang.String> com.vodafone.vvc.push.controllers.PushNotificationController.sendPushNotification(com.vodafone.vvc.push.resources.PushNotificationInputDataResource) throws org.codehaus.jackson.JsonProcessingException,java.io.IOException]
2013-11-12 11:12:28,305 | drivexone-push | DEBUG | DefaultListableBeanFactory
|Returning cached instance of singleton bean 'pushNotificationController'
2013-11-12 11:12:28,305 | drivexone-push | TRACE | DispatcherServlet
|Testing handler adapter [org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter@7c1570c3]
2013-11-12 11:12:28,308 | drivexone-push | DEBUG | RequestResponseBodyMethodProcessor
|Reading [com.vodafone.vvc.push.resources.PushNotificationInputDataResource] as "application/json" using [org.springframework.http.converter.json.MappingJacksonHttpMessageConverter@22a89f5c]
2013-11-12 11:12:28,310 | drivexone-push | TRACE | ServletInvocableHandlerMethod
|Invoking [sendPushNotification] method with arguments [com.vodafone.vvc.push.resources.PushNotificationInputDataResource@6b680257]
2013-11-12 11:12:28,327 | drivexone-push | INFO | PushNotificationController
|Push Notification will be sent to the APNS Official Provider
2013-11-12 11:12:28,328 | drivexone-push | TRACE | ServletInvocableHandlerMethod
|Method [sendPushNotification] returned [<201 Created,Request has been received and is being processed,{}>]
2013-11-12 11:12:28,331 | drivexone-push | DEBUG | HttpEntityMethodProcessor
|Written [Request has been received and is being processed] as "application/json;charset=utf-8" using [org.springframework.http.converter.StringHttpMessageConverter@1faed341]
2013-11-12 11:12:28,331 | drivexone-push | DEBUG | DispatcherServlet
|Null ModelAndView returned to DispatcherServlet with name 'appServlet': assuming HandlerAdapter completed request handling
2013-11-12 11:12:28,331 | drivexone-push | TRACE | DispatcherServlet
|Cleared thread-bound request context: org.apache.catalina.connector.RequestFacade@24d6fbe7
2013-11-12 11:12:28,332 | drivexone-push | DEBUG | DispatcherServlet
|Successfully completed request
2013-11-12 11:12:28,332 | drivexone-push | TRACE | XmlWebApplicationContext
|Publishing event in WebApplicationContext for namespace 'appServlet-servlet': ServletRequestHandledEvent: url=[/vvc-push/push]; client=[127.0.0.1]; method=[POST]; servlet=[appServlet]; session=[null]; user=[null]; time=[32ms]; status=[OK]
2013-11-12 11:12:28,332 | drivexone-push | TRACE | XmlWebApplicationContext
|Publishing event in Root WebApplicationContext: ServletRequestHandledEvent: url=[/vvc-push/push]; client=[127.0.0.1]; method=[POST]; servlet=[appServlet]; session=[null]; user=[null]; time=[32ms]; status=[OK]
2013-11-12 11:12:28,337 | drivexone-push | INFO | ApplePushNotification
|The payload is being sent to APNS
2013-11-12 11:12:28,338 | drivexone-push | TRACE | ApnsClientThread
|ApnsClientThread-1 sending SendableApnsPushNotification [sequenceNumber=0, token=3f3ad78b00c5e7f740dd06fa5357333e0d3140d640b4b08c068d4927f0790f90, payload={"aps":{"alert":{"loc-args":["MILE_CRUNCHETE3"],"loc-key":"VVC_BADGE"}}}, deliveryInvalidation=null]
2013-11-12 11:12:28,434 | drivexone-push | TRACE | ApnsClientThread
|ApnsClientThread-1 successfully wrote notification 0
2013-11-12 11:12:51,161 | drivexone-push | DEBUG | ApnsClientThread
|ApnsClientThread-1 waiting for connection to close.
2013-11-12 11:12:51,162 | drivexone-push | DEBUG | ApnsClientThread
|ApnsClientThread-1 beginning connection process.
2013-11-12 11:12:51,183 | drivexone-push | ERROR | ApnsClientThread
|ApnsClientThread-1 failed to connect to APNs gateway.
java.nio.channels.UnresolvedAddressException: null
at sun.nio.ch.Net.checkAddress(Net.java:85) ~[na:1.7.0_21]
at sun.nio.ch.SocketChannelImpl.connect(SocketChannelImpl.java:597) ~[na:1.7.0_21]
at io.netty.channel.socket.nio.NioSocketChannel.doConnect(NioSocketChannel.java:195) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.channel.nio.AbstractNioChannel$AbstractNioUnsafe.connect(AbstractNioChannel.java:172) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.channel.DefaultChannelPipeline$HeadHandler.connect(DefaultChannelPipeline.java:1008) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.channel.DefaultChannelHandlerContext.invokeConnect(DefaultChannelHandlerContext.java:491) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.channel.DefaultChannelHandlerContext.connect(DefaultChannelHandlerContext.java:476) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.handler.ssl.SslHandler.connect(SslHandler.java:370) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.channel.DefaultChannelHandlerContext.invokeConnect(DefaultChannelHandlerContext.java:491) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.channel.DefaultChannelHandlerContext.connect(DefaultChannelHandlerContext.java:476) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.channel.ChannelOutboundHandlerAdapter.connect(ChannelOutboundHandlerAdapter.java:47) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.channel.DefaultChannelHandlerContext.invokeConnect(DefaultChannelHandlerContext.java:491) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.channel.DefaultChannelHandlerContext.connect(DefaultChannelHandlerContext.java:476) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.channel.DefaultChannelHandlerContext.connect(DefaultChannelHandlerContext.java:461) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.channel.DefaultChannelPipeline.connect(DefaultChannelPipeline.java:847) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.channel.AbstractChannel.connect(AbstractChannel.java:198) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.bootstrap.Bootstrap$2.run(Bootstrap.java:165) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:354) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:366) ~[netty-all-4.0.7.Final.jar:na]
at io.netty.util.concurrent.SingleThreadEventExecutor$2.run(SingleThreadEventExecutor.java:101) ~[netty-all-4.0.7.Final.jar:na]
at java.lang.Thread.run(Thread.java:722) ~[na:1.7.0_21]
2013-11-12 11:12:51,184 | drivexone-push | DEBUG | ApnsClientThread
|ApnsClientThread-1 beginning connection process.
2013-11-12 11:13:02,481 | drivexone-push | DEBUG | ApnsClientThread
|ApnsClientThread-1 connected.
2013-11-12 11:13:02,481 | drivexone-push | DEBUG | ApnsClientThread
|ApnsClientThread-1 waiting for TLS handshake.
2013-11-12 11:13:02,977 | drivexone-push | DEBUG | ApnsClientThread
|ApnsClientThread-1 successfully completed TLS handshake.
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment