Mar 31 12:25:17 charon 14[IKE] sending DPD request Mar 31 12:25:17 charon 14[IKE] queueing IKE_DPD task Mar 31 12:25:17 charon 14[IKE] activating new tasks Mar 31 12:25:17 charon 14[IKE] activating IKE_DPD task Mar 31 12:25:17 charon 14[ENC] generating INFORMATIONAL request 325 [ ] Mar 31 12:25:17 charon 14[NET] sending packet: from XX.XX.XX.XX[500] to YY.YY.YY.YY[500] (57 bytes) Mar 31 12:25:17 charon 14[NET] received packet: from YY.YY.YY.YY[500] to XX.XX.XX.XX[500] (57 bytes) Mar 31 12:25:17 charon 14[ENC] parsed INFORMATIONAL response 325 [ ] Mar 31 12:25:17 charon 14[IKE] activating new tasks Mar 31 12:25:17 charon 14[IKE] nothing to initiate Mar 31 12:25:26 charon 06[IKE] sending DPD request Mar 31 12:25:26 charon 06[IKE] queueing IKE_DPD task Mar 31 12:25:26 charon 06[IKE] activating new tasks Mar 31 12:25:26 charon 06[IKE] activating IKE_DPD task Mar 31 12:25:26 charon 06[ENC] generating INFORMATIONAL request 326 [ ] Mar 31 12:25:26 charon 06[NET] sending packet: from XX.XX.XX.XX[500] to YY.YY.YY.YY[500] (57 bytes) Mar 31 12:25:26 charon 06[NET] received packet: from YY.YY.YY.YY[500] to XX.XX.XX.XX[500] (57 bytes) Mar 31 12:25:26 charon 06[ENC] parsed INFORMATIONAL response 326 [ ] Mar 31 12:25:26 charon 06[IKE] activating new tasks Mar 31 12:25:26 charon 06[IKE] nothing to initiate Mar 31 12:25:27 charon 06[NET] received packet: from YY.YY.YY.YY[500] to XX.XX.XX.XX[500] (549 bytes) Mar 31 12:25:27 charon 06[ENC] parsed CREATE_CHILD_SA request 96 [ N(REKEY_SA) N(ESP_TFC_PAD_N) SA No KE TSi TSr ] Mar 31 12:25:27 charon 06[IKE] received ESP_TFC_PADDING_NOT_SUPPORTED, not using ESPv3 TFC padding Mar 31 12:25:27 charon 06[CFG] selecting proposal: Mar 31 12:25:27 charon 06[CFG] proposal matches Mar 31 12:25:27 charon 06[CFG] received proposals: ESP:AES_GCM_16_256/MODP_2048/NO_EXT_SEQ, ESP:AES_GCM_12_256/MODP_2048/NO_EXT_SEQ, ESP:AES_GCM_8_256/MODP_2048/NO_EXT_SEQ Mar 31 12:25:27 charon 06[CFG] configured proposals: ESP:AES_GCM_16_256/MODP_2048/NO_EXT_SEQ, ESP:AES_GCM_12_256/MODP_2048/NO_EXT_SEQ, ESP:AES_GCM_8_256/MODP_2048/NO_EXT_SEQ Mar 31 12:25:27 charon 06[CFG] selected proposal: ESP:AES_GCM_16_256/MODP_2048/NO_EXT_SEQ Mar 31 12:25:27 charon 06[CFG] selecting traffic selectors for us: Mar 31 12:25:27 charon 06[CFG] config: 192.168.2.0/24|/0, received: 192.168.2.0/24|/0 => match: 192.168.2.0/24|/0 Mar 31 12:25:27 charon 06[CFG] selecting traffic selectors for other: Mar 31 12:25:27 charon 06[CFG] config: 192.168.1.0/24|/0, received: 192.168.1.0/24|/0 => match: 192.168.1.0/24|/0 Mar 31 12:25:27 charon 06[CHD] CHILD_SA con1000{182} state change: CREATED => INSTALLING Mar 31 12:25:27 charon 06[CHD] using AES_GCM_16 for encryption Mar 31 12:25:27 charon 06[CHD] adding inbound ESP SA Mar 31 12:25:27 charon 06[CHD] SPI 0xc4e9f0c8, src YY.YY.YY.YY dst XX.XX.XX.XX Mar 31 12:25:27 charon 06[CHD] registering outbound ESP SA Mar 31 12:25:27 charon 06[CHD] SPI 0xc6adf097, src XX.XX.XX.XX dst YY.YY.YY.YY Mar 31 12:25:27 charon 06[IKE] inbound CHILD_SA con1000{182} established with SPIs c4e9f0c8_i c6adf097_o and TS 192.168.2.0/24|/0 === 192.168.1.0/24|/0 Mar 31 12:25:27 charon 06[CHD] CHILD_SA con1000{182} state change: INSTALLING => INSTALLED Mar 31 12:25:27 charon 06[CHD] CHILD_SA con1000{181} state change: INSTALLED => REKEYING Mar 31 12:25:27 charon 06[CHD] CHILD_SA con1000{181} state change: REKEYING => REKEYED Mar 31 12:25:27 charon 06[ENC] generating CREATE_CHILD_SA response 96 [ N(ESP_TFC_PAD_N) SA No KE TSi TSr ] Mar 31 12:25:27 charon 06[NET] sending packet: from XX.XX.XX.XX[500] to YY.YY.YY.YY[500] (457 bytes) Mar 31 12:25:27 charon 06[NET] received packet: from YY.YY.YY.YY[500] to XX.XX.XX.XX[500] (69 bytes) Mar 31 12:25:27 charon 06[ENC] parsed INFORMATIONAL request 97 [ D ] Mar 31 12:25:27 charon 06[IKE] received DELETE for ESP CHILD_SA with SPI cd1f6f62 Mar 31 12:25:27 charon 06[IKE] closing CHILD_SA con1000{181} with SPIs c7beb356_i (1603749 bytes) cd1f6f62_o (2121024 bytes) and TS 192.168.2.0/24|/0 === 192.168.1.0/24|/0 Mar 31 12:25:27 charon 06[IKE] sending DELETE for ESP CHILD_SA with SPI c7beb356 Mar 31 12:25:27 charon 06[CHD] CHILD_SA con1000{181} state change: REKEYED => DELETING Mar 31 12:25:27 charon 06[IKE] CHILD_SA closed Mar 31 12:25:27 charon 06[CHD] CHILD_SA con1000{181} state change: DELETING => DELETED Mar 31 12:25:27 charon 06[CHD] adding outbound ESP SA Mar 31 12:25:27 charon 06[CHD] SPI 0xc6adf097, src XX.XX.XX.XX dst YY.YY.YY.YY Mar 31 12:25:27 charon 06[IKE] outbound CHILD_SA con1000{182} established with SPIs c4e9f0c8_i c6adf097_o and TS 192.168.2.0/24|/0 === 192.168.1.0/24|/0 Mar 31 12:25:27 charon 06[ENC] generating INFORMATIONAL response 97 [ D ] Mar 31 12:25:27 charon 06[NET] sending packet: from XX.XX.XX.XX[500] to YY.YY.YY.YY[500] (69 bytes) Mar 31 12:25:32 charon 12[IKE] sending DPD request Mar 31 12:25:32 charon 12[IKE] queueing IKE_DPD task Mar 31 12:25:32 charon 12[IKE] activating new tasks Mar 31 12:25:32 charon 12[IKE] activating IKE_DPD task Mar 31 12:25:32 charon 12[ENC] generating INFORMATIONAL request 327 [ ] Mar 31 12:25:32 charon 12[NET] sending packet: from XX.XX.XX.XX[500] to YY.YY.YY.YY[500] (57 bytes) Mar 31 12:25:32 charon 06[IKE] queueing CHILD_DELETE task Mar 31 12:25:32 charon 06[IKE] delaying task initiation, INFORMATIONAL exchange in progress Mar 31 12:25:32 charon 06[NET] received packet: from YY.YY.YY.YY[500] to XX.XX.XX.XX[500] (57 bytes) Mar 31 12:25:32 charon 06[ENC] parsed INFORMATIONAL response 327 [ ] Mar 31 12:25:32 charon 06[IKE] activating new tasks Mar 31 12:25:32 charon 06[IKE] activating CHILD_DELETE task Mar 31 12:25:32 charon 06[CHD] CHILD_SA con1000{181} state change: DELETED => DESTROYING Mar 31 12:25:32 charon 06[IKE] activating new tasks Mar 31 12:25:32 charon 06[IKE] nothing to initiate Mar 31 12:25:37 charon 12[NET] received packet: from YY.YY.YY.YY[500] to XX.XX.XX.XX[500] (57 bytes) Mar 31 12:25:37 charon 12[ENC] parsed INFORMATIONAL request 98 [ ] Mar 31 12:25:37 charon 12[ENC] generating INFORMATIONAL response 98 [ ] Mar 31 12:25:37 charon 12[NET] sending packet: from XX.XX.XX.XX[500] to YY.YY.YY.YY[500] (57 bytes) Mar 31 12:25:42 charon 06[NET] received packet: from YY.YY.YY.YY[500] to XX.XX.XX.XX[500] (57 bytes) Mar 31 12:25:42 charon 06[ENC] parsed INFORMATIONAL request 99 [ ] Mar 31 12:25:42 charon 06[ENC] generating INFORMATIONAL response 99 [ ] Mar 31 12:25:42 charon 06[NET] sending packet: from XX.XX.XX.XX[500] to YY.YY.YY.YY[500] (57 bytes) Mar 31 12:25:47 charon 06[NET] received packet: from YY.YY.YY.YY[500] to XX.XX.XX.XX[500] (57 bytes) Mar 31 12:25:47 charon 06[ENC] parsed INFORMATIONAL request 100 [ ] Mar 31 12:25:47 charon 06[ENC] generating INFORMATIONAL response 100 [ ] Mar 31 12:25:47 charon 06[NET] sending packet: from XX.XX.XX.XX[500] to YY.YY.YY.YY[500] (57 bytes) Mar 31 12:25:52 charon 06[NET] received packet: from YY.YY.YY.YY[500] to XX.XX.XX.XX[500] (57 bytes) Mar 31 12:25:52 charon 06[ENC] parsed INFORMATIONAL request 101 [ ] Mar 31 12:25:52 charon 06[ENC] generating INFORMATIONAL response 101 [ ] Mar 31 12:25:52 charon 06[NET] sending packet: from XX.XX.XX.XX[500] to YY.YY.YY.YY[500] (57 bytes) Mar 31 12:25:52 charon 06[NET] received packet: from YY.YY.YY.YY[500] to XX.XX.XX.XX[500] (57 bytes) Mar 31 12:25:52 charon 06[ENC] parsed INFORMATIONAL request 101 [ ] Mar 31 12:25:52 charon 06[ENC] generating INFORMATIONAL response 101 [ ] Mar 31 12:25:52 charon 06[NET] sending packet: from XX.XX.XX.XX[500] to YY.YY.YY.YY[500] (57 bytes) Mar 31 12:25:57 charon 06[NET] received packet: from YY.YY.YY.YY[500] to XX.XX.XX.XX[500] (57 bytes) Mar 31 12:25:57 charon 06[ENC] parsed INFORMATIONAL request 102 [ ] Mar 31 12:25:57 charon 06[ENC] generating INFORMATIONAL response 102 [ ] Mar 31 12:25:57 charon 06[NET] sending packet: from XX.XX.XX.XX[500] to YY.YY.YY.YY[500] (57 bytes) Mar 31 12:26:01 charon 06[KNL] creating rekey job for CHILD_SA ESP/0xc78b4470/YY.YY.YY.YY Mar 31 12:26:01 charon 06[IKE] queueing CHILD_REKEY task Mar 31 12:26:01 charon 06[IKE] activating new tasks Mar 31 12:26:01 charon 06[IKE] activating CHILD_REKEY task Mar 31 12:26:01 charon 06[CFG] proposing traffic selectors for us: Mar 31 12:26:01 charon 06[CFG] 192.168.2.0/24|/0 Mar 31 12:26:01 charon 06[CFG] proposing traffic selectors for other: Mar 31 12:26:01 charon 06[CFG] 192.168.1.0/24|/0 Mar 31 12:26:01 charon 06[CFG] configured proposals: ESP:AES_GCM_16_256/MODP_2048/NO_EXT_SEQ, ESP:AES_GCM_12_256/MODP_2048/NO_EXT_SEQ, ESP:AES_GCM_8_256/MODP_2048/NO_EXT_SEQ Mar 31 12:26:01 charon 06[IKE] establishing CHILD_SA con1000{183} reqid 6 Mar 31 12:26:01 charon 06[CHD] CHILD_SA con1000{180} state change: INSTALLED => REKEYING Mar 31 12:26:01 charon 06[ENC] generating CREATE_CHILD_SA request 328 [ N(REKEY_SA) N(ESP_TFC_PAD_N) SA No KE TSi TSr ] Mar 31 12:26:01 charon 06[NET] sending packet: from XX.XX.XX.XX[500] to YY.YY.YY.YY[500] (549 bytes) Mar 31 12:26:01 charon 06[NET] received packet: from YY.YY.YY.YY[500] to XX.XX.XX.XX[500] (457 bytes) Mar 31 12:26:01 charon 06[ENC] parsed CREATE_CHILD_SA response 328 [ N(ESP_TFC_PAD_N) SA No KE TSi TSr ] Mar 31 12:26:01 charon 06[IKE] received ESP_TFC_PADDING_NOT_SUPPORTED notify Mar 31 12:26:01 charon 06[IKE] received ESP_TFC_PADDING_NOT_SUPPORTED, not using ESPv3 TFC padding Mar 31 12:26:01 charon 06[CFG] selecting proposal: Mar 31 12:26:01 charon 06[CFG] proposal matches Mar 31 12:26:01 charon 06[CFG] received proposals: ESP:AES_GCM_16_256/MODP_2048/NO_EXT_SEQ Mar 31 12:26:01 charon 06[CFG] configured proposals: ESP:AES_GCM_16_256/MODP_2048/NO_EXT_SEQ, ESP:AES_GCM_12_256/MODP_2048/NO_EXT_SEQ, ESP:AES_GCM_8_256/MODP_2048/NO_EXT_SEQ Mar 31 12:26:01 charon 06[CFG] selected proposal: ESP:AES_GCM_16_256/MODP_2048/NO_EXT_SEQ Mar 31 12:26:01 charon 06[CFG] selecting traffic selectors for us: Mar 31 12:26:01 charon 06[CFG] config: 192.168.2.0/24|/0, received: 192.168.2.0/24|/0 => match: 192.168.2.0/24|/0 Mar 31 12:26:01 charon 06[CFG] selecting traffic selectors for other: Mar 31 12:26:01 charon 06[CFG] config: 192.168.1.0/24|/0, received: 192.168.1.0/24|/0 => match: 192.168.1.0/24|/0 Mar 31 12:26:01 charon 06[CHD] CHILD_SA con1000{183} state change: CREATED => INSTALLING Mar 31 12:26:01 charon 06[CHD] using AES_GCM_16 for encryption Mar 31 12:26:01 charon 06[CHD] adding inbound ESP SA Mar 31 12:26:01 charon 06[CHD] SPI 0xccf02eed, src YY.YY.YY.YY dst XX.XX.XX.XX Mar 31 12:26:01 charon 06[CHD] registering outbound ESP SA Mar 31 12:26:01 charon 06[CHD] SPI 0xc05593bd, src XX.XX.XX.XX dst YY.YY.YY.YY Mar 31 12:26:01 charon 06[IKE] inbound CHILD_SA con1000{183} established with SPIs ccf02eed_i c05593bd_o and TS 192.168.2.0/24|/0 === 192.168.1.0/24|/0 Mar 31 12:26:01 charon 06[CHD] CHILD_SA con1000{183} state change: INSTALLING => INSTALLED Mar 31 12:26:01 charon 06[CHD] adding outbound ESP SA Mar 31 12:26:01 charon 06[CHD] SPI 0xc05593bd, src XX.XX.XX.XX dst YY.YY.YY.YY Mar 31 12:26:01 charon 06[IKE] outbound CHILD_SA con1000{183} established with SPIs ccf02eed_i c05593bd_o and TS 192.168.2.0/24|/0 === 192.168.1.0/24|/0 Mar 31 12:26:01 charon 06[CHD] CHILD_SA con1000{180} state change: REKEYING => REKEYED Mar 31 12:26:01 charon 06[IKE] reinitiating already active tasks Mar 31 12:26:01 charon 06[IKE] CHILD_REKEY task Mar 31 12:26:01 charon 06[IKE] closing CHILD_SA con1000{180} with SPIs c6e93b5e_i (0 bytes) c78b4470_o (0 bytes) and TS 192.168.2.0/24|/0 === 192.168.1.0/24|/0 Mar 31 12:26:01 charon 06[IKE] sending DELETE for ESP CHILD_SA with SPI c6e93b5e Mar 31 12:26:01 charon 06[CHD] CHILD_SA con1000{180} state change: REKEYED => DELETING Mar 31 12:26:01 charon 06[ENC] generating INFORMATIONAL request 329 [ D ] Mar 31 12:26:01 charon 06[NET] sending packet: from XX.XX.XX.XX[500] to YY.YY.YY.YY[500] (69 bytes) Mar 31 12:26:01 charon 05[NET] received packet: from YY.YY.YY.YY[500] to XX.XX.XX.XX[500] (69 bytes) Mar 31 12:26:01 charon 05[ENC] parsed INFORMATIONAL response 329 [ D ] Mar 31 12:26:01 charon 05[IKE] received DELETE for ESP CHILD_SA with SPI c78b4470 Mar 31 12:26:01 charon 05[IKE] CHILD_SA closed Mar 31 12:26:01 charon 05[CHD] CHILD_SA con1000{180} state change: DELETING => DELETED Mar 31 12:26:01 charon 05[IKE] activating new tasks Mar 31 12:26:01 charon 05[IKE] nothing to initiate Mar 31 12:26:06 charon 05[NET] received packet: from YY.YY.YY.YY[500] to XX.XX.XX.XX[500] (57 bytes) Mar 31 12:26:06 charon 05[ENC] parsed INFORMATIONAL request 103 [ ] Mar 31 12:26:06 charon 05[ENC] generating INFORMATIONAL response 103 [ ] Mar 31 12:26:06 charon 05[NET] sending packet: from XX.XX.XX.XX[500] to YY.YY.YY.YY[500] (57 bytes) Mar 31 12:26:06 charon 05[IKE] queueing CHILD_DELETE task Mar 31 12:26:06 charon 05[IKE] activating new tasks Mar 31 12:26:06 charon 05[IKE] activating CHILD_DELETE task Mar 31 12:26:06 charon 05[CHD] CHILD_SA con1000{180} state change: DELETED => DESTROYING Mar 31 12:26:06 charon 05[IKE] activating new tasks Mar 31 12:26:06 charon 05[IKE] nothing to initiate Mar 31 12:26:11 charon 11[NET] received packet: from YY.YY.YY.YY[500] to XX.XX.XX.XX[500] (57 bytes) Mar 31 12:26:11 charon 11[ENC] parsed INFORMATIONAL request 104 [ ] Mar 31 12:26:11 charon 11[ENC] generating INFORMATIONAL response 104 [ ] Mar 31 12:26:11 charon 11[NET] sending packet: from XX.XX.XX.XX[500] to YY.YY.YY.YY[500] (57 bytes) Mar 31 12:26:16 charon 11[NET] received packet: from YY.YY.YY.YY[500] to XX.XX.XX.XX[500] (57 bytes) Mar 31 12:26:16 charon 11[ENC] parsed INFORMATIONAL request 105 [ ]