server crash after send notification in production running on docker (exited with code 0)

[addame2]

Attaching to qvisa_stg
qvisa_stg | 2025-03-15T11:11:21.648973Z INFO qvisa: 706: Starting the application...
qvisa_stg | 2025-03-15T11:11:21.648987Z DEBUG qvisa::database: 14: Attempting to retrieve the database pool...
qvisa_stg | 2025-03-15T11:11:21.648995Z INFO qvisa::database: 27: Initializing the database pool...
qvisa_stg | 2025-03-15T11:11:21.649088Z INFO qvisa::database: 58: Database configuration - host: pgbouncer, port: 6432, dbname: faster_visa
qvisa_stg | 2025-03-15T11:11:21.649103Z INFO qvisa::database: 34: Database connection manager created successfully.
qvisa_stg | 2025-03-15T11:11:21.649132Z INFO qvisa::database: 44: Database connection pool built successfully.
qvisa_stg | 2025-03-15T11:11:21.649142Z INFO qvisa: 710: Database pool initialized successfully.
qvisa_stg | 2025-03-15T11:11:21.649146Z INFO qvisa: 720: Fetching FCM manager...
qvisa_stg | 2025-03-15T11:11:21.649159Z INFO qvisa::fcm_manager: 47: Initializing FCMManager...
qvisa_stg | 2025-03-15T11:11:21.691797Z INFO qvisa::fcm_manager: 60: FCMManager initialized successfully
qvisa_stg | 2025-03-15T11:11:21.691916Z INFO qvisa: 722: FCM manager fetched successfully.
qvisa_stg | 2025-03-15T11:11:21.692007Z INFO qvisa: 724: Fetching Firestore service...
qvisa_stg | 2025-03-15T11:11:21.692123Z INFO qvisa::fb_db_service: 39: Initializing Firestore with project ID: qvisa-xxxx-fid
qvisa_stg | 2025-03-15T11:11:21.692878Z INFO firestore::db: 162: Creating a new database client. database_path="projects/qvisa-verified-fid/databases/(default)" api_url="https://firestore.googleapis.com" token_scopes="https://www.googleapis.com/auth/cloud-platform"
qvisa_stg | 2025-03-15T11:11:21.692907Z DEBUG gcloud_sdk::api_client: 45: Creating a new Google API client for https://firestore.googleapis.com. Scopes: ["https://www.googleapis.com/auth/cloud-platform"]
qvisa_stg | 2025-03-15T11:11:21.693092Z TRACE tonic::transport::channel::service::reconnect: 73: poll_ready; idle
qvisa_stg | 2025-03-15T11:11:21.693111Z TRACE tonic::transport::channel::service::reconnect: 87: poll_ready; connecting
qvisa_stg | 2025-03-15T11:11:21.693172Z TRACE hyper_util::client::legacy::connect::http: 383: Http::connect; scheme=Some("https"), host=Some("firestore.googleapis.com"), port=None
qvisa_stg | 2025-03-15T11:11:21.693240Z TRACE tonic::transport::channel::service::reconnect: 93: poll_ready; not ready
qvisa_stg | 2025-03-15T11:11:21.695247Z TRACE tonic::transport::channel::service::reconnect: 87: poll_ready; connecting
qvisa_stg | 2025-03-15T11:11:21.695312Z DEBUG hyper_util::client::legacy::connect::http: 657: connecting to 216.58.209.202:443
qvisa_stg | 2025-03-15T11:11:21.695534Z TRACE tonic::transport::channel::service::reconnect: 93: poll_ready; not ready
qvisa_stg | 2025-03-15T11:11:21.699010Z TRACE tonic::transport::channel::service::reconnect: 87: poll_ready; connecting
qvisa_stg | 2025-03-15T11:11:21.699065Z DEBUG hyper_util::client::legacy::connect::http: 660: connected to 216.58.209.202:443
qvisa_stg | 2025-03-15T11:11:21.699288Z TRACE tonic::transport::channel::service::reconnect: 93: poll_ready; not ready
qvisa_stg | 2025-03-15T11:11:21.702647Z TRACE tonic::transport::channel::service::reconnect: 87: poll_ready; connecting
qvisa_stg | 2025-03-15T11:11:21.704373Z DEBUG h2::client: 1290: binding client connection
qvisa_stg | 2025-03-15T11:11:21.704419Z DEBUG h2::client: 1295: client connection bound
qvisa_stg | 2025-03-15T11:11:21.704459Z DEBUG FramedWrite::buffer{frame=Settings { flags: (0x0), enable_push: 0, initial_window_size: 2097152, max_frame_size: 16384, max_header_list_size: 16384 }}: h2::codec::framed_write: 213: send frame=Settings { flags: (0x0), enable_push: 0, initial_window_size: 2097152, max_frame_size: 16384, max_header_list_size: 16384 }
qvisa_stg | 2025-03-15T11:11:21.704473Z TRACE FramedWrite::buffer{frame=Settings { flags: (0x0), enable_push: 0, initial_window_size: 2097152, max_frame_size: 16384, max_header_list_size: 16384 }}: h2::frame::settings: 218: encoding SETTINGS; len=24
qvisa_stg | 2025-03-15T11:11:21.704479Z TRACE FramedWrite::buffer{frame=Settings { flags: (0x0), enable_push: 0, initial_window_size: 2097152, max_frame_size: 16384, max_header_list_size: 16384 }}: h2::frame::settings: 224: encoding setting; val=EnablePush(0)
qvisa_stg | 2025-03-15T11:11:21.704484Z TRACE FramedWrite::buffer{frame=Settings { flags: (0x0), enable_push: 0, initial_window_size: 2097152, max_frame_size: 16384, max_header_list_size: 16384 }}: h2::frame::settings: 224: encoding setting; val=InitialWindowSize(2097152)
qvisa_stg | 2025-03-15T11:11:21.704488Z TRACE FramedWrite::buffer{frame=Settings { flags: (0x0), enable_push: 0, initial_window_size: 2097152, max_frame_size: 16384, max_header_list_size: 16384 }}: h2::frame::settings: 224: encoding setting; val=MaxFrameSize(16384)
qvisa_stg | 2025-03-15T11:11:21.704492Z TRACE FramedWrite::buffer{frame=Settings { flags: (0x0), enable_push: 0, initial_window_size: 2097152, max_frame_size: 16384, max_header_list_size: 16384 }}: h2::frame::settings: 224: encoding setting; val=MaxHeaderListSize(16384)
qvisa_stg | 2025-03-15T11:11:21.704496Z TRACE FramedWrite::buffer{frame=Settings { flags: (0x0), enable_push: 0, initial_window_size: 2097152, max_frame_size: 16384, max_header_list_size: 16384 }}: h2::codec::framed_write: 262: encoded settings rem=33
qvisa_stg | 2025-03-15T11:11:21.704506Z TRACE h2::proto::streams::flow_control: 124: inc_window; sz=65535; old=0; new=65535
qvisa_stg | 2025-03-15T11:11:21.704510Z TRACE h2::proto::streams::flow_control: 124: inc_window; sz=65535; old=0; new=65535
qvisa_stg | 2025-03-15T11:11:21.704514Z TRACE h2::proto::streams::prioritize: 94: Prioritize::new; flow=FlowControl { window_size: Window(65535), available: Window(65535) }
qvisa_stg | 2025-03-15T11:11:21.704523Z TRACE h2::proto::streams::recv: 467: set_target_connection_window; target=5242880; available=65535, reserved=0
qvisa_stg | 2025-03-15T11:11:21.704538Z TRACE tonic::transport::channel::service::reconnect: 113: poll_ready; connected
qvisa_stg | 2025-03-15T11:11:21.704542Z TRACE tonic::transport::channel::service::reconnect: 119: poll_ready; ready
qvisa_stg | 2025-03-15T11:11:21.704555Z DEBUG gcloud_sdk::token_source: 61: Finding default token for scopes: ["https://www.googleapis.com/auth/cloud-platform"]
qvisa_stg | 2025-03-15T11:11:21.704565Z DEBUG gcloud_sdk::token_source::credentials: 156: Reading credentials from file: "/usr/src/qvisa/src/credentials/qvisa-verified-fid-firebase-adminsdk-fbsvc-bd10742c81.json"
qvisa_stg | 2025-03-15T11:11:21.704636Z DEBUG gcloud_sdk::token_source: 64: Creating token based on environment variable: GOOGLE_APPLICATION_CREDENTIALS
qvisa_stg | 2025-03-15T11:11:21.704645Z INFO qvisa: 727: Firestore service initialized successfully.
qvisa_stg | 2025-03-15T11:11:21.704667Z TRACE Connection{peer=Client}:poll: h2::proto::connection: 268: connection.state=Open
qvisa_stg | 2025-03-15T11:11:21.704683Z TRACE Connection{peer=Client}:poll:FramedRead::poll_next: h2::codec::framed_read: 382: poll
qvisa_stg | 2025-03-15T11:11:21.704703Z DEBUG Connection{peer=Client}:poll:FramedWrite::buffer{frame=WindowUpdate { stream_id: StreamId(0), size_increment: 5177345 }}: h2::codec::framed_write: 213: send frame=WindowUpdate { stream_id: StreamId(0), size_increment: 5177345 }
qvisa_stg | 2025-03-15T11:11:21.704710Z TRACE Connection{peer=Client}:poll:FramedWrite::buffer{frame=WindowUpdate { stream_id: StreamId(0), size_increment: 5177345 }}: h2::frame::window_update: 51: encoding WINDOW_UPDATE; id=StreamId(0)
qvisa_stg | 2025-03-15T11:11:21.704717Z TRACE Connection{peer=Client}:poll:FramedWrite::buffer{frame=WindowUpdate { stream_id: StreamId(0), size_increment: 5177345 }}: h2::codec::framed_write: 274: encoded window_update rem=46
qvisa_stg | 2025-03-15T11:11:21.704725Z TRACE Connection{peer=Client}:poll: h2::proto::streams::flow_control: 124: inc_window; sz=5177345; old=65535; new=5242880
qvisa_stg | 2025-03-15T11:11:21.704734Z TRACE Connection{peer=Client}:poll: h2::proto::streams::prioritize: 528: poll_complete
qvisa_stg | 2025-03-15T11:11:21.704739Z TRACE Connection{peer=Client}:poll: h2::proto::streams::prioritize: 890: schedule_pending_open
qvisa_stg | 2025-03-15T11:11:21.704750Z TRACE Connection{peer=Client}:poll:FramedWrite::flush: h2::codec::framed_write: 143: queued_data_frame=false
qvisa_stg | 2025-03-15T11:11:21.704803Z TRACE Connection{peer=Client}:poll:FramedWrite::flush: h2::codec::framed_write: 159: flushing buffer
qvisa_stg | 2025-03-15T11:11:21.704825Z TRACE tower::buffer::worker: 108: worker polling for next message
qvisa_stg | 2025-03-15T11:11:21.707089Z TRACE Connection{peer=Client}:poll: h2::proto::connection: 268: connection.state=Open
qvisa_stg | 2025-03-15T11:11:21.707122Z TRACE Connection{peer=Client}:poll:FramedRead::poll_next: h2::codec::framed_read: 382: poll
qvisa_stg | 2025-03-15T11:11:21.707247Z TRACE Connection{peer=Client}:poll:FramedRead::poll_next: h2::codec::framed_read: 389: read.bytes=27
qvisa_stg | 2025-03-15T11:11:21.707264Z TRACE Connection{peer=Client}:poll:FramedRead::poll_next:FramedRead::decode_frame{offset=27}: h2::codec::framed_read: 132: decoding frame from 27B
qvisa_stg | 2025-03-15T11:11:21.707561Z TRACE Connection{peer=Client}:poll:FramedRead::poll_next:FramedRead::decode_frame{offset=27}: h2::codec::framed_read: 144: frame.kind=Settings
qvisa_stg | 2025-03-15T11:11:21.707588Z DEBUG Connection{peer=Client}:poll:FramedRead::poll_next: h2::codec::framed_read: 404: received frame=Settings { flags: (0x0), max_concurrent_streams: 100, initial_window_size: 1048576, max_header_list_size: 65536 }
qvisa_stg | 2025-03-15T11:11:21.707597Z TRACE Connection{peer=Client}:poll: h2::proto::connection: 514: recv SETTINGS frame=Settings { flags: (0x0), max_concurrent_streams: 100, initial_window_size: 1048576, max_header_list_size: 65536 }
qvisa_stg | 2025-03-15T11:11:21.707616Z DEBUG Connection{peer=Client}:poll:poll_ready:FramedWrite::buffer{frame=Settings { flags: (0x1: ACK) }}: h2::codec::framed_write: 213: send frame=Settings { flags: (0x1: ACK) }
qvisa_stg | 2025-03-15T11:11:21.707625Z TRACE Connection{peer=Client}:poll:poll_ready:FramedWrite::buffer{frame=Settings { flags: (0x1: ACK) }}: h2::frame::settings: 218: encoding SETTINGS; len=0
qvisa_stg | 2025-03-15T11:11:21.707633Z TRACE Connection{peer=Client}:poll:poll_ready:FramedWrite::buffer{frame=Settings { flags: (0x1: ACK) }}: h2::codec::framed_write: 262: encoded settings rem=9
qvisa_stg | 2025-03-15T11:11:21.707641Z TRACE Connection{peer=Client}:poll:poll_ready: h2::proto::settings: 134: ACK sent; applying settings
qvisa_stg | 2025-03-15T11:11:21.707649Z TRACE Connection{peer=Client}:poll:FramedRead::poll_next: h2::codec::framed_read: 382: poll
qvisa_stg | 2025-03-15T11:11:21.707654Z TRACE Connection{peer=Client}:poll:FramedRead::poll_next: h2::codec::framed_read: 389: read.bytes=13
qvisa_stg | 2025-03-15T11:11:21.707661Z TRACE Connection{peer=Client}:poll:FramedRead::poll_next:FramedRead::decode_frame{offset=13}: h2::codec::framed_read: 132: decoding frame from 13B
qvisa_stg | 2025-03-15T11:11:21.707667Z TRACE Connection{peer=Client}:poll:FramedRead::poll_next:FramedRead::decode_frame{offset=13}: h2::codec::framed_read: 144: frame.kind=WindowUpdate
qvisa_stg | 2025-03-15T11:11:21.707679Z DEBUG Connection{peer=Client}:poll:FramedRead::poll_next: h2::codec::framed_read: 404: received frame=WindowUpdate { stream_id: StreamId(0), size_increment: 983041 }
qvisa_stg | 2025-03-15T11:11:21.707686Z TRACE Connection{peer=Client}:poll: h2::proto::connection: 540: recv WINDOW_UPDATE frame=WindowUpdate { stream_id: StreamId(0), size_increment: 983041 }
qvisa_stg | 2025-03-15T11:11:21.707693Z TRACE Connection{peer=Client}:poll: h2::proto::streams::flow_control: 124: inc_window; sz=983041; old=65535; new=1048576
qvisa_stg | 2025-03-15T11:11:21.707705Z TRACE Connection{peer=Client}:poll:FramedRead::poll_next: h2::codec::framed_read: 382: poll
qvisa_stg | 2025-03-15T11:11:21.707710Z TRACE Connection{peer=Client}:poll:FramedRead::poll_next: h2::codec::framed_read: 389: read.bytes=9
qvisa_stg | 2025-03-15T11:11:21.707717Z TRACE Connection{peer=Client}:poll:FramedRead::poll_next:FramedRead::decode_frame{offset=9}: h2::codec::framed_read: 132: decoding frame from 9B
qvisa_stg | 2025-03-15T11:11:21.707723Z TRACE Connection{peer=Client}:poll:FramedRead::poll_next:FramedRead::decode_frame{offset=9}: h2::codec::framed_read: 144: frame.kind=Settings
qvisa_stg | 2025-03-15T11:11:21.707734Z DEBUG Connection{peer=Client}:poll:FramedRead::poll_next: h2::codec::framed_read: 404: received frame=Settings { flags: (0x1: ACK) }
qvisa_stg | 2025-03-15T11:11:21.707740Z TRACE Connection{peer=Client}:poll: h2::proto::connection: 514: recv SETTINGS frame=Settings { flags: (0x1: ACK) }
qvisa_stg | 2025-03-15T11:11:21.707751Z DEBUG Connection{peer=Client}:poll: h2::proto::settings: 56: received settings ACK; applying Settings { flags: (0x0), enable_push: 0, initial_window_size: 2097152, max_frame_size: 16384, max_header_list_size: 16384 }
qvisa_stg | 2025-03-15T11:11:21.707756Z TRACE Connection{peer=Client}:poll: h2::proto::streams::recv: 514: update_initial_window_size; new=2097152; old=65535
qvisa_stg | 2025-03-15T11:11:21.707761Z TRACE Connection{peer=Client}:poll: h2::proto::streams::recv: 549: incrementing all windows; inc=2031617
qvisa_stg | 2025-03-15T11:11:21.707769Z TRACE Connection{peer=Client}:poll:FramedRead::poll_next: h2::codec::framed_read: 382: poll
qvisa_stg | 2025-03-15T11:11:21.707781Z TRACE Connection{peer=Client}:poll: h2::proto::streams::prioritize: 528: poll_complete
qvisa_stg | 2025-03-15T11:11:21.707785Z TRACE Connection{peer=Client}:poll: h2::proto::streams::prioritize: 890: schedule_pending_open
qvisa_stg | 2025-03-15T11:11:21.707794Z TRACE Connection{peer=Client}:poll:FramedWrite::flush: h2::codec::framed_write: 143: queued_data_frame=false
qvisa_stg | 2025-03-15T11:11:21.707845Z TRACE Connection{peer=Client}:poll:FramedWrite::flush: h2::codec::framed_write: 159: flushing buffer
qvisa_stg | 2025-03-15T11:11:36.705655Z INFO qvisa: 768: Sending FCM notification to device: dW2ODcUJaEE7itI1sF8pL_:xxxxxxxx
qvisa_stg | 2025-03-15T11:11:36.705690Z INFO qvisa::fcm_manager: 80: Preparing FCM notification for device: dW2ODcUJaEE7itI1sF8pL_:xxxxxxx
qvisa_stg | 2025-03-15T11:11:36.713351Z TRACE hyper_util::client::legacy::connect::http: 383: Http::connect; scheme=Some("https"), host=Some("oauth2.googleapis.com"), port=None
qvisa_stg | 2025-03-15T11:11:36.719752Z DEBUG hyper_util::client::legacy::connect::http: 657: connecting to 216.58.210.138:443
qvisa_stg | 2025-03-15T11:11:36.722008Z DEBUG hyper_util::client::legacy::connect::http: 660: connected to 216.58.210.138:443
qvisa_stg exited with code 0
qvisa_stg | 2025-03-15T11:11:38.661808Z INFO qvisa::fcm_manager: 60: FCMManager initialized successfully
qvisa_stg | 2025-03-15T11:11:38.661829Z INFO qvisa: 722: FCM manager fetched successfully.
qvisa_stg | 2025-03-15T11:11:38.661833Z INFO qvisa: 724: Fetching Firestore service...
qvisa_stg | 2025-03-15T11:11:38.661840Z INFO qvisa::fb_db_service: 39: Initializing Firestore with project ID: qvisa-verified-fid
qvisa_stg | 2025-03-15T11:11:38.661860Z INFO firestore::db: 162: Creating a new database client. database_path="projects/qvisa-verified-fid/databases/(default)" api_url="https://firestore.googleapis.com" token_scopes="https://www.googleapis.com/auth/cloud-platform"
qvisa_stg | 2025-03-15T11:11:38.661869Z DEBUG gcloud_sdk::api_client: 45: Creating a new Google API client for https://firestore.googleapis.com. Scopes: ["https://www.googleapis.com/auth/cloud-platform"]
qvisa_stg | 2025-03-15T11:11:38.661935Z TRACE tonic::transport::channel::service::reconnect: 73: poll_ready; idle
qvisa_stg | 2025-03-15T11:11:38.661949Z TRACE tonic::transport::channel::service::reconnect: 87: poll_ready; connecting
qvisa_stg | 2025-03-15T11:11:38.661956Z TRACE hyper_util::client::legacy::connect::http: 383: Http::connect; scheme=Some("https"), host=Some("firestore.googleapis.com"), port=None
qvisa_stg | 2025-03-15T11:11:38.662003Z TRACE tonic::transport::channel::service::reconnect: 93: poll_ready; not ready
qvisa_stg | 2025-03-15T11:11:38.665278Z TRACE tonic::transport::channel::service::reconnect: 87: poll_ready; connecting
qvisa_stg | 2025-03-15T11:11:38.665674Z DEBUG hyper_util::client::legacy::connect::http: 657: connecting to 216.58.211.234:443
qvisa_stg | 2025-03-15T11:11:38.665930Z TRACE tonic::transport::channel::service::reconnect: 93: poll_ready; not ready
qvisa_stg | 2025-03-15T11:11:38.667148Z TRACE tonic::transport::channel::service::reconnect: 87: poll_ready; connecting
qvisa_stg | 2025-03-15T11:11:38.667184Z DEBUG hyper_util::client::legacy::connect::http: 660: connected to 216.58.211.234:443
qvisa_stg | 2025-03-15T11:11:38.667455Z TRACE tonic::transport::channel::service::reconnect: 93: poll_ready; not ready
qvisa_stg | 2025-03-15T11:11:38.671156Z TRACE tonic::transport::channel::service::reconnect: 87: poll_ready; connecting
qvisa_stg | 2025-03-15T11:11:38.672635Z DEBUG h2::client: 1290: binding client connection
qvisa_stg | 2025-03-15T11:11:38.672678Z DEBUG h2::client: 1295: client connection bound