14:11:36.155 [info] Setup running ...
14:11:36.155 [info] Directories verified. Res = :ok
14:11:36.155 [info] Setup finished processing hooks (Mode=:normal)...
14:11:36.156 [info] Starting Elixir.MongoosePush.Router with Cowboy on https://127.0.0.1:303
14:11:36.149 [error] Supervisor 'Elixir.Logger.Supervisor' had child 'Elixir.Logger.ErrorHandler' started with 'Elixir.Logger.Watcher':start_link({error_logger,'Elixir.Logger.ErrorHandler',{true,false,500}}) at <0.1159.0> exit with reason normal in context child_terminated
14:11:36.154 [info] Application lager started on node '[email protected]'
14:11:36.154 [info] Application asn1 started on node '[email protected]'
14:11:36.154 [info] Application crypto started on node '[email protected]'
14:11:36.154 [info] Application public_key started on node '[email protected]'
14:11:36.154 [info] Application ssl started on node '[email protected]'
14:11:36.154 [info] Application chatterbox started on node '[email protected]'
14:11:36.154 [info] Application metrics started on node '[email protected]'
14:11:36.154 [info] Application ssl_verify_fun started on node '[email protected]'
14:11:36.154 [info] Application certifi started on node '[email protected]'
14:11:36.155 [info] Application mimerl started on node '[email protected]'
14:11:36.155 [info] Application unicode_util_compat started on node '[email protected]'
14:11:36.155 [info] Application idna started on node '[email protected]'
14:11:36.155 [info] Application hackney started on node '[email protected]'
14:11:36.155 [info] Application httpoison started on node '[email protected]'
14:11:36.155 [info] Application pigeon started on node '[email protected]'
14:11:36.155 [info] Application pobox started on node '[email protected]'
14:11:36.155 [info] Setup running ...
14:11:36.155 [info] Directories verified. Res = ok
14:11:36.155 [info] Setup finished processing hooks (Mode=normal)...
14:11:36.155 [info] Application setup started on node '[email protected]'
14:11:36.156 [info] Starting reporters with []
14:11:36.156 [info] Application exometer_core started on node '[email protected]'
14:11:36.156 [info] Application elixometer started on node '[email protected]'
14:11:36.156 [info] Application exometer started on node '[email protected]'
14:11:36.156 [info] Application mime started on node '[email protected]'
14:11:36.156 [info] Application plug started on node '[email protected]'
14:11:36.174 [info] Starting FCM pool with API key AAAAC3sFy48:APA91bEHZGVesP4TycnyY5UhLC9jk2Ilo9CY4RISfOL8S7CvPcQCL4Wv-dkLuopIzF7Xmn9_A1kp7WYVJW1PDH35Ju0LD_EdzHzcwvV1bhOuOEAGCB22bJ-WEmBndr154HwfoPGIYmoV
14:11:36.175 [warn] Unable to extract APNS topic from the dev certificate due to: :no_extension
14:11:36.175 [debug] Using user-defined default APNS topic: com.banksalaam.waafi
Interactive Elixir (1.6.0-dev) - press Ctrl+C to exit (type h() ENTER for help)
iex([email protected])1> 14:11:36.649 [debug] Lager installed handler lager_backend_throttle into lager_event
14:11:37.008 [warning] lager_error_logger_h dropped 103 messages in the last second that exceeded the limit of 50 messages/sec
14:11:54.127 [debug] POST /v2/notification/e4a89d2f3f7f3196d5869a33454e22b3821b6fbd51ebf6446c627a5fd5a992e8
14:11:54.127 [debug] POST /v2/notification/e4a89d2f3f7f3196d5869a33454e22b3821b6fbd51ebf6446c627a5fd5a992e8
14:11:54.127 [debug] POST /v2/notification/e4a89d2f3f7f3196d5869a33454e22b3821b6fbd51ebf6446c627a5fd5a992e8
14:11:54.127 [info] Reconnecting to push service provider before request
14:11:54.127 [info] Reconnecting to push service provider before request
14:11:54.127 [debug] Supervisor inet_gethost_native_sup started undefined at pid <0.1442.0>
14:11:54.127 [debug] Supervisor kernel_safe_sup started inet_gethost_native:start_link() at pid <0.1441.0>
14:11:54.129 [debug] POST /v2/notification/e4a89d2f3f7f3196d5869a33454e22b3821b6fbd51ebf6446c627a5fd5a992e8
14:11:54.321 [debug] Spawning ack timeout alarm clock: <0.1440.0> + {#Ref<0.0.1.516>,{settings,4096,1,unlimited,65535,16384,unlimited}}
14:11:54.321 [debug] Spawning ack timeout alarm clock: <0.1439.0> + {#Ref<0.0.8.508>,{settings,4096,1,unlimited,65535,16384,unlimited}}
14:11:54.322 [debug] NewStream {active_stream,1,<0.1447.0>,<0.1408.0>,65535,65535,undefined,false}
14:11:54.322 [debug] [client] added stream #1 to {stream_set,client,{peer_subset,unlimited,1,0,3,[{active_stream,1,<0.1447.0>,<0.1408.0>,65535,65535,undefined,false}]},{peer_subset,unlimited,0,0,2,[]}}
14:11:54.322 [debug] [client] Received SETTINGS
14:11:54.322 [debug] [client] IWS undefined
14:11:54.322 [debug] [client] Sent Settings ACK
14:11:54.322 [debug] [client] {send headers, 1, [{<<":method">>,<<"POST">>},{<<":path">>,<<"/3/device/e4a89d2f3f7f3196d5869a33454e22b3821b6fbd51ebf6446c627a5fd5a992e8">>},{<<":scheme">>,<<"https">>},{<<":authority">>,<<"api.push.apple.com">>},{<<"content-length">>,<<"95">>},{<<"content-type">>,<<"application/json">>},{<<"accept">>,<<"application/json">>},{<<"apns-topic">>,<<"com.banksalaam.waafi">>}]}
14:11:54.323 [debug] [client] Send Body Stream 1
14:11:54.323 [debug] NewStream {active_stream,3,<0.1448.0>,<0.1408.0>,65535,65535,undefined,false}
14:11:54.323 [debug] [client] added stream #3 to {stream_set,client,{peer_subset,1000,2,0,5,[{active_stream,1,<0.1447.0>,<0.1408.0>,65440,65535,done,true},{active_stream,3,<0.1448.0>,<0.1408.0>,65535,65535,undefined,false}]},{peer_subset,unlimited,0,0,2,[]}}
14:11:54.323 [debug] [client] {send headers, 3, [{<<":method">>,<<"POST">>},{<<":path">>,<<"/3/device/e4a89d2f3f7f3196d5869a33454e22b3821b6fbd51ebf6446c627a5fd5a992e8">>},{<<":scheme">>,<<"https">>},{<<":authority">>,<<"api.push.apple.com">>},{<<"content-length">>,<<"95">>},{<<"content-type">>,<<"application/json">>},{<<"accept">>,<<"application/json">>},{<<"apns-topic">>,<<"nil">>}]}
14:11:54.323 [debug] [client] Send Body Stream 3
14:11:54.323 [debug] NewStream {active_stream,1,<0.1449.0>,<0.1406.0>,65535,65535,undefined,false}
14:11:54.323 [debug] [client] added stream #1 to {stream_set,client,{peer_subset,unlimited,1,0,3,[{active_stream,1,<0.1449.0>,<0.1406.0>,65535,65535,undefined,false}]},{peer_subset,unlimited,0,0,2,[]}}
14:11:54.323 [debug] [client] Received SETTINGS
14:11:54.323 [debug] [client] IWS undefined
14:11:54.323 [debug] [client] Sent Settings ACK
14:11:54.323 [debug] [client] {send headers, 1, [{<<":method">>,<<"POST">>},{<<":path">>,<<"/3/device/e4a89d2f3f7f3196d5869a33454e22b3821b6fbd51ebf6446c627a5fd5a992e8">>},{<<":scheme">>,<<"https">>},{<<":authority">>,<<"api.push.apple.com">>},{<<"content-length">>,<<"95">>},{<<"content-type">>,<<"application/json">>},{<<"accept">>,<<"application/json">>},{<<"apns-topic">>,<<"nil">>}]}
14:11:54.323 [debug] [client] Send Body Stream 1
14:11:54.324 [debug] NewStream {active_stream,3,<0.1450.0>,<0.1406.0>,65535,65535,undefined,false}
14:11:54.324 [debug] [client] added stream #3 to {stream_set,client,{peer_subset,1000,2,0,5,[{active_stream,1,<0.1449.0>,<0.1406.0>,65440,65535,done,true},{active_stream,3,<0.1450.0>,<0.1406.0>,65535,65535,undefined,false}]},{peer_subset,unlimited,0,0,2,[]}}
14:11:54.324 [debug] [client] {send headers, 3, [{<<":method">>,<<"POST">>},{<<":path">>,<<"/3/device/e4a89d2f3f7f3196d5869a33454e22b3821b6fbd51ebf6446c627a5fd5a992e8">>},{<<":scheme">>,<<"https">>},{<<":authority">>,<<"api.push.apple.com">>},{<<"content-length">>,<<"95">>},{<<"content-type">>,<<"application/json">>},{<<"accept">>,<<"application/json">>},{<<"apns-topic">>,<<"nil">>}]}
14:11:54.324 [debug] [client] Send Body Stream 3
14:11:54.427 [debug] [client][connected] {frame, "[Frame Header: L:0, T:"SETTINGS", F:1, StrId:0] | []"}
14:11:54.427 [debug] [client][connected] {frame, "[Frame Header: L:0, T:"SETTINGS", F:1, StrId:0] | []"}
14:11:54.427 [debug] [client] Received SETTINGS ACK
14:11:54.427 [debug] [client] Received SETTINGS ACK
14:11:54.427 [debug] [client][connected] {frame, "[Frame Header: L:30, T:"SETTINGS", F:0, StrId:0] | [{header_table_size,4096},\n {max_concurrent_streams,1000},\n {initial_window_size,65535},\n {max_frame_size,16384},\n {max_header_list_size,8000}]"}
14:11:54.427 [debug] [client][connected] {frame, "[Frame Header: L:30, T:"SETTINGS", F:0, StrId:0] | [{header_table_size,4096},\n {max_concurrent_streams,1000},\n {initial_window_size,65535},\n {max_frame_size,16384},\n {max_header_list_size,8000}]"}
14:11:54.427 [debug] [client] Received SETTINGS
14:11:54.427 [debug] [client] Received SETTINGS
14:11:54.427 [debug] old IWS: 65535 new IWS: 65535
14:11:54.427 [debug] old IWS: 65535 new IWS: 65535
14:11:54.427 [debug] [client] Sent Settings ACK
14:11:54.427 [debug] [client] Sent Settings ACK
14:11:54.427 [debug] [client][connected] {frame, "[Frame Header: L:37, T:"HEADERS", F:4, StrId:1] | [Headers: {headers,undefined,\n <<140,64,133,29,117,33,99,73,156,133,156,32,113,204,15,172,\n 38,175,194,181,215,66,250,208,134,252,43,58,23,92,132,23,\n 112,178,200,174,255>>}]"}
14:11:54.427 [debug] [client] Received HEADERS Frame for Stream 1
14:11:54.427 [debug] [client][connected] {frame, "[Frame Header: L:37, T:"HEADERS", F:4, StrId:1] | [Headers: {headers,undefined,\n <<140,64,133,29,117,33,99,73,156,188,229,208,179,129,203,\n 203,54,237,241,44,245,240,190,182,7,27,117,102,153,11,32,\n 106,232,91,125,182,127>>}]"}
14:11:54.427 [debug] [client] Received HEADERS Frame for Stream 1
14:11:54.427 [debug] read_binary L: 27, actually: 27
14:11:54.427 [debug] read_binary L: 27, actually: 27
14:11:54.427 [debug] [client][connected] {frame, "[Frame Header: L:27, T:"DATA", F:1, StrId:1] | [Data: {data: <<"{\"reason\":\"BadDeviceToken\"}">> ...}]"}
14:11:54.427 [debug] [client][connected] {frame, "[Frame Header: L:27, T:"DATA", F:1, StrId:1] | [Data: {data: <<"{\"reason\":\"BadDeviceToken\"}">> ...}]"}
14:11:54.427 [debug] [client] Received DATA Frame for Stream 1
14:11:54.427 [debug] [client] Received DATA Frame for Stream 1
14:11:54.427 [info] [client] Stream 1 WindowUpdate 27
14:11:54.427 [info] [client] Stream 1 WindowUpdate 27
14:11:54.427 [debug] [client][connected] {frame, "[Frame Header: L:32, T:"HEADERS", F:4, StrId:3] | [Headers: {headers,undefined,\n <<140,31,47,156,113,235,237,181,117,156,43,62,253,214,214,\n 125,172,55,150,111,0,94,89,247,0,232,128,39,25,115,5,223>>}]"}
14:11:54.427 [debug] [client][connected] {frame, "[Frame Header: L:32, T:"HEADERS", F:4, StrId:3] | [Headers: {headers,undefined,\n <<140,31,47,156,188,203,112,185,11,225,88,13,94,105,103,\n 222,189,117,102,238,187,121,96,190,203,247,240,133,151,\n 128,248,127>>}]"}
14:11:54.427 [debug] [client] Received HEADERS Frame for Stream 3
14:11:54.428 [error] Error code 400 (:bad_device_token): The specified device token was bad. Verify that the request contains a valid token and
that the token matches the environment.
14:11:54.427 [debug] [client] Received HEADERS Frame for Stream 3
14:11:54.428 [warn] Unable to complete push request due to bad_device_token
14:11:54.428 [error] Error code 400 (:bad_device_token): The specified device token was bad. Verify that the request contains a valid token and
that the token matches the environment.
14:11:54.428 [debug] Sent 500 in 300ms
14:11:54.428 [warn] Unable to complete push request due to bad_device_token
14:11:54.428 [debug] Sent 500 in 300ms
14:11:54.427 [debug] read_binary L: 27, actually: 27
14:11:54.428 [error] Error code 400 (:bad_device_token): The specified device token was bad. Verify that the request contains a valid token and
that the token matches the environment.
14:11:54.428 [warn] Unable to complete push request due to bad_device_token
14:11:54.428 [debug] Sent 500 in 301ms
14:11:54.428 [error] Error code 400 (:bad_device_token): The specified device token was bad. Verify that the request contains a valid token and
that the token matches the environment.
14:11:54.427 [debug] read_binary L: 27, actually: 27
14:11:54.428 [warn] Unable to complete push request due to bad_device_token
14:11:54.428 [debug] Sent 500 in 298ms
14:11:54.428 [debug] [client][connected] {frame, "[Frame Header: L:27, T:"DATA", F:1, StrId:3] | [Data: {data: <<"{\"reason\":\"BadDeviceToken\"}">> ...}]"}
14:11:54.428 [debug] [client][connected] {frame, "[Frame Header: L:27, T:"DATA", F:1, StrId:3] | [Data: {data: <<"{\"reason\":\"BadDeviceToken\"}">> ...}]"}
14:11:54.428 [debug] [client] Received DATA Frame for Stream 3
14:11:54.428 [debug] [client] Received DATA Frame for Stream 3
14:11:54.428 [info] [client] Stream 3 WindowUpdate 27
14:11:54.428 [info] [client] Stream 3 WindowUpdate 27
14:11:54.428 [debug] _Other = {false,false}
14:11:54.428 [debug] _Other = {false,false}
14:11:54.428 [debug] _Other = {false,false}