2025-10-31T14:16:00.586765+08:00 [debug] clientid: 145060600691, msg: emqx_channel_takeover_end, peername: 39.144.129.26:8278, username: test
2025-10-31T14:16:00.586938+08:00 [debug] tag: SOCKET, clientid: 145060600691, msg: emqx_connection_terminated, peername: 39.144.129.26:8278, username: test, reason: {shutdown,takenover}
2025-10-31T14:16:00.587012+08:00 [info] clientid: 145060600691, msg: terminate, peername: 39.144.129.26:8278, username: test, reason: {shutdown,takenover}
2025-10-31T14:16:00.587153+08:00 [debug] clientid: 145060600691, msg: insert_channel_info, peername: 39.144.129.26:8279, username: test
2025-10-31T14:16:00.587335+08:00 [debug] msg: emqx_cm_clean_down, client_id: <<“145060600691”>>
2025-10-31T14:16:00.587558+08:00 [debug] tag: MQTT, clientid: 145060600691, msg: mqtt_packet_sent, peername: 39.144.129.26:8279, username: test, packet: CONNACK(Q0, R0, D0, AckFlags=1, ReasonCode=0)
2025-10-31T14:16:00.720556+08:00 [debug] tag: MQTT, clientid: 145060600691, msg: raw_bin_received, peername: 39.144.129.26:8279, username: test, size: 24, type: hex, bin: 8216000200112F746573742F757365722F75706461746500
2025-10-31T14:16:00.720932+08:00 [debug] tag: MQTT, clientid: 145060600691, msg: mqtt_packet_received, peername: 39.144.129.26:8279, username: test, packet: SUBSCRIBE(Q1, R0, D0, PacketId=2 TopicFilters=[/test/user/update(#{nl => 0,qos => 0,rap => 0,rh => 0})])
2025-10-31T14:16:00.721125+08:00 [debug] tag: AUTHZ, clientid: 145060600691, msg: authorization_module_ignore, peername: 39.144.129.26:8279, username: test, topic: /test/user/update, module: emqx_authz_client_info, action: SUBSCRIBE(Q0), authorize_type: client_info
2025-10-31T14:16:00.721285+08:00 [debug] tag: AUTHZ, clientid: 145060600691, msg: authorization_matched_allow, peername: 39.144.129.26:8279, username: test, topic: /test/user/update, module: emqx_authz_file, action: SUBSCRIBE(Q0), authorize_type: file
2025-10-31T14:16:00.721392+08:00 [info] tag: AUTHZ, clientid: 145060600691, msg: authorization_permission_allowed, peername: 39.144.129.26:8279, username: test, topic: /test/user/update, action: SUBSCRIBE(Q0), source: file
2025-10-31T14:16:00.721710+08:00 [debug] tag: SUBSCRIBE, clientid: 145060600691, msg: subscribe, peername: 39.144.129.26:8279, username: test, topic: /test/user/update, sub_id: <<“145060600691”>>, sub_opts: #{nl => 0,qos => 0,rap => 0,rh => 0,sub_props => #{}}
2025-10-31T14:16:00.721997+08:00 [debug] tag: MQTT, clientid: 145060600691, msg: mqtt_packet_sent, peername: 39.144.129.26:8279, username: test, packet: SUBACK(Q0, R0, D0, PacketId=2, ReasonCodes=[0])
2025-10-31T14:16:00.722277+08:00 [debug] clientid: 145060600691, msg: insert_channel_info, peername: 39.144.129.26:8279, username: test
2025-10-31T14:16:00.722660+08:00 [debug] tag: MQTT, clientid: 145060600691, msg: mqtt_packet_sent, peername: 39.144.129.26:8279, username: test, packet: PUBLISH(Q0, R1, D0, Topic=/test/user/update, PacketId=undefined, Payload(text)={“did”:“603010000019”,“sdid”:“000000000001”,“productid”:“203010001”,“mt”:“update”,“mid”:“c31f0f5-0f8d-40bf-a0ea-3dde2637b186”,“ts”:1.76189e+12,“data”:{“pros”:{“010001”:0,“010011”:229.573,“010012”:0,“010013”:0,“010021”:0,“010022”:0,“010023”:0,“010100”:0,“010101”:0,“010102”:0,“010103”:0,“010112”:1,“010113”:1,“010114”:1,“010115”:1,“010116”:4.996,“010201”:31.92,“010202”:29.06,“010203”:30.04,“030010”:751586,“030020”:528326,“030030”:858875,“030040”:801815,“030050”:911704,“030060”:334553,“030070”:802951,“030080”:789188,“030000”:340286,“000003”:1,“000005”:0,“000002”:“000”}}})
2025-10-31T14:16:14.128856+08:00 [debug] tag: MQTT, clientid: westinghouseDataLoop, msg: raw_bin_received, peername: 139.224.66.219:57900, username: test, size: 2, type: hex, bin: C000
2025-10-31T14:16:14.129079+08:00 [debug] tag: MQTT, clientid: westinghouseDataLoop, msg: mqtt_packet_received, peername: 139.224.66.219:57900, username: test, packet: PINGREQ(Q0, R0, D0)
2025-10-31T14:16:14.129222+08:00 [debug] tag: MQTT, clientid: westinghouseDataLoop, msg: mqtt_packet_sent, peername: 139.224.66.219:57900, username: test, packet: PINGRESP(Q0, R0, D0)
2025-10-31T14:16:14.129418+08:00 [debug] tag: MQTT, clientid: westinghouseDataLocal, msg: raw_bin_received, peername: 139.224.66.219:57902, username: test, size: 2, type: hex, bin: C000
2025-10-31T14:16:14.129666+08:00 [debug] tag: MQTT, clientid: westinghouseDataLocal, msg: mqtt_packet_received, peername: 139.66.219.218:57902, username: test, packet: PINGREQ(Q0, R0, D0)
2025-10-31T14:16:14.129890+08:00 [debug] tag: MQTT, clientid: westinghouseDataLocal, msg: mqtt_packet_sent, peername: 139.224.66.219:57902, username: test, packet: PINGRESP(Q0, R0, D0)
2025-10-31T14:16:35.036631+08:00 [debug] tag: MQTT, clientid: 603010000019, msg: raw_bin_received, peername: 36.19.65.183:39957, username: test, size: 2, type: hex, bin: E000
2025-10-31T14:16:35.036960+08:00 [debug] tag: MQTT, clientid: 603010000019, msg: mqtt_packet_received, peername: 36.19.65.183:39957, username: test, packet: DISCONNECT(Q0, R0, D0, ReasonCode=0)
2025-10-31T14:16:35.037165+08:00 [debug] tag: SOCKET, clientid: 603010000019, msg: socket_force_closed, peername: 36.19.65.183:39957, username: test, reason: normal
2025-10-31T14:16:35.082388+08:00 [debug] tag: MQTT, msg: raw_bin_received, peername: 36.19.65.183:39958, size: 98, type: hex, bin: 106000044D51545404CC003C000C3630333031303030303031390011746573742F757365722F6F66666C696E6500257B22646964223A22363033303130303030303139222C226D74223A226F66666C696E65222C0004746573740006313233343536
2025-10-31T14:16:35.082679+08:00 [debug] tag: MQTT, clientid: 603010000019, msg: mqtt_packet_received, peername: 36.19.65.183:39958, username: test, packet: CONNECT(Q0, R0, D0, ClientId=603010000019, ProtoName=MQTT, ProtoVsn=4, CleanStart=false, KeepAlive=60, Username=test, Password=******, Will(Q1, R0, Topic=test/user/offline Payload(text)={“did”:“603010000019”,“mt”:“offline”,))
2025-10-31T14:16:35.082923+08:00 [debug] tag: QUERY, clientid: 603010000019, msg: mysql_connector_received, peername: 36.19.65.183:39958, username: test, state: #{pool_name => <<“emqx_authn_mysql:3”>>,query_templates => #{{emqx_authn_mysql,prepstmt} => {[<<“SELECT password_hash FROM mqtt_user where username = ? LIMIT 1”>>],}},prepares => ok}, connector: <<“emqx_authn_mysql:3”>>, sql: emqx_authn_mysql
2025-10-31T14:16:35.084033+08:00 [debug] tag: AUTHN, clientid: 603010000019, msg: authenticator_result, peername: 36.19.65.183:39958, username: test, result: {ok,#{is_superuser => false}}, authenticator: <<“password_based:mysql”>>
2025-10-31T14:16:35.084224+08:00 [debug] tag: AUTHN, clientid: 603010000019, msg: authentication_result, peername: 36.19.65.183:39958, username: test, reason: chain_result, result: {stop,{ok,#{is_superuser => false}}}
2025-10-31T14:16:35.084461+08:00 [debug] tag: SUBSCRIBE, clientid: 603010000019, msg: subscribe, peername: 36.19.65.183:39958, username: test, topic: /test/user/connect, sub_id: <<“603010000019”>>, sub_opts: #{nl => 0,qos => 0,rap => 0,rh => 0,sub_props => #{}}
2025-10-31T14:16:35.084703+08:00 [debug] tag: SUBSCRIBE, clientid: 603010000019, msg: subscribe, peername: 36.19.65.183:39958, username: test, topic: /test/user/control, sub_id: <<“603010000019”>>, sub_opts: #{nl => 0,qos => 0,rap => 0,rh => 0,sub_props => #{}}
2025-10-31T14:16:35.084847+08:00 [debug] tag: SUBSCRIBE, clientid: 603010000019, msg: subscribe, peername: 36.19.65.183:39958, username: test, topic: /test/user/event, sub_id: <<“603010000019”>>, sub_opts: #{nl => 0,qos => 0,rap => 0,rh => 0,sub_props => #{}}
2025-10-31T14:16:35.085009+08:00 [debug] tag: SUBSCRIBE, clientid: 603010000019, msg: subscribe, peername: 36.19.65.183:39958, username: test, topic: /test/user/get, sub_id: <<“603010000019”>>, sub_opts: #{nl => 0,qos => 0,rap => 0,rh => 0,sub_props => #{}}
2025-10-31T14:16:35.085141+08:00 [debug] tag: SUBSCRIBE, clientid: 603010000019, msg: subscribe, peername: 36.19.65.183:39958, username: test, topic: /test/user/set, sub_id: <<“603010000019”>>, sub_opts: #{nl => 0,qos => 0,rap => 0,rh => 0,sub_props => #{}}
2025-10-31T14:16:35.085255+08:00 [debug] tag: SUBSCRIBE, clientid: 603010000019, msg: subscribe, peername: 36.19.65.183:39958, username: test, topic: /test/user/set_reply, sub_id: <<“603010000019”>>, sub_opts: #{nl => 0,qos => 0,rap => 0,rh => 0,sub_props => #{}}
2025-10-31T14:16:35.085451+08:00 [debug] tag: SUBSCRIBE, clientid: 603010000019, msg: subscribe, peername: 36.19.65.183:39958, username: test, topic: /test/user/update, sub_id: <<“603010000019”>>, sub_opts: #{nl => 0,qos => 0,rap => 0,rh => 0,sub_props => #{}}
2025-10-31T14:16:35.085696+08:00 [debug] tag: UNSUBSCRIBE, clientid: 603010000019, msg: unsubscribe, peername: 36.19.65.183:39957, username: test, topic: /test/user/connect, sub_opts: #{nl => 0,qos => 0,subid => <<“603010000019”>>,rap => 0,rh => 0,sub_props => #{}}
2025-10-31T14:16:35.085932+08:00 [debug] tag: UNSUBSCRIBE, clientid: 603010000019, msg: unsubscribe, peername: 36.19.65.183:39957, username: test, topic: /test/user/control, sub_opts: #{nl => 0,qos => 0,subid => <<“603010000019”>>,rap => 0,rh => 0,sub_props => #{}}
2025-10-31T14:16:35.086135+08:00 [debug] tag: UNSUBSCRIBE, clientid: 603010000019, msg: unsubscribe, peername: 36.19.65.183:39957, username: test, topic: /test/user/event, sub_opts: #{nl => 0,qos => 0,subid => <<“603010000019”>>,rap => 0,rh => 0,sub_props => #{}}
2025-10-31T14:16:35.086301+08:00 [debug] tag: UNSUBSCRIBE, clientid: 603010000019, msg: unsubscribe, peername: 36.19.65.183:39957, username: test, topic: /test/user/get, sub_opts: #{nl => 0,qos => 0,subid => <<“603010000019”>>,rap => 0,rh => 0,sub_props => #{}}
2025-10-31T14:16:35.086445+08:00 [debug] tag: UNSUBSCRIBE, clientid: 603010000019, msg: unsubscribe, peername: 36.19.65.183:39957, username: test, topic: /test/user/set, sub_opts: #{nl => 0,qos => 0,subid => <<“603010000019”>>,rap => 0,rh => 0,sub_props => #{}}
2025-10-31T14:16:35.086618+08:00 [debug] tag: UNSUBSCRIBE, clientid: 603010000019, msg: unsubscribe, peername: 36.19.65.183:39957, username: test, topic: /test/user/set_reply, sub_opts: #{nl => 0,qos => 0,subid => <<“603010000019”>>,rap => 0,rh => 0,sub_props => #{}}
2025-10-31T14:16:35.086814+08:00 [debug] tag: UNSUBSCRIBE, clientid: 603010000019, msg: unsubscribe, peername: 36.19.65.183:39957, username: test, topic: /test/user/update, sub_opts: #{nl => 0,qos => 0,subid => <<“603010000019”>>,rap => 0,rh => 0,sub_props => #{}}
2025-10-31T14:16:35.086948+08:00 [debug] clientid: 603010000019, msg: emqx_channel_takeover_end, peername: 36.19.65.183:39957, username: test
2025-10-31T14:16:35.087021+08:00 [debug] tag: SOCKET, clientid: 603010000019, msg: emqx_connection_terminated, peername: 36.19.65.183:39957, username: test, reason: {shutdown,takenover}
2025-10-31T14:16:35.087142+08:00 [info] clientid: 603010000019, msg: terminate, peername: 36.19.65.183:39957, username: test, reason: {shutdown,takenover}
2025-10-31T14:16:35.087231+08:00 [debug] clientid: 603010000019, msg: insert_channel_info, peername: 36.19.65.183:39958, username: test
2025-10-31T14:16:35.087358+08:00 [debug] msg: emqx_cm_clean_down, client_id: <<“603010000019”>>
2025-10-31T14:16:35.087443+08:00 [debug] tag: MQTT, clientid: 603010000019, msg: mqtt_packet_sent, peername: 36.19.65.183:39958, username: test, packet: CONNACK(Q0, R0, D0, AckFlags=1, ReasonCode=0)
2025-10-31T14:16:35.215427+08:00 [debug] tag: MQTT, clientid: 603010000019, msg: raw_bin_received, peername: 36.19.65.183:39958, username: test, size: 24, type: hex, bin: 8216000200112F746573742F757365722F75706461746500
2025-10-31T14:16:35.215660+08:00 [debug] tag: MQTT, clientid: 603010000019, msg: mqtt_packet_received, peername: 36.19.65.183:39958, username: test, packet: SUBSCRIBE(Q1, R0, D0, PacketId=2 TopicFilters=[/test/user/update(#{nl => 0,qos => 0,rap => 0,rh => 0})])
2025-10-31T14:16:35.215830+08:00 [debug] tag: AUTHZ, clientid: 603010000019, msg: authorization_module_ignore, peername: 36.19.65.183:39958, username: test, topic: /test/user/update, module: emqx_authz_client_info, action: SUBSCRIBE(Q0), authorize_type: client_info
2025-10-31T14:16:35.215967+08:00 [debug] tag: AUTHZ, clientid: 603010000019, msg: authorization_matched_allow, peername: 36.19.65.183:39958, username: test, topic: /test/user/update, module: emqx_authz_file, action: SUBSCRIBE(Q0), authorize_type: file
2025-10-31T14:16:35.216059+08:00 [info] tag: AUTHZ, clientid: 603010000019, msg: authorization_permission_allowed, peername: 36.19.65.183:39958, username: test, topic: /test/user/update, action: SUBSCRIBE(Q0), source: file
2025-10-31T14:16:35.216208+08:00 [debug] tag: SUBSCRIBE, clientid: 603010000019, msg: subscribe, peername: 36.19.65.183:39958, username: test, topic: /test/user/update, sub_id: <<“603010000019”>>, sub_opts: #{nl => 0,qos => 0,rap => 0,rh => 0,sub_props => #{}}
2025-10-31T14:16:35.216425+08:00 [debug] tag: MQTT, clientid: 603010000019, msg: mqtt_packet_sent, peername: 36.19.65.183:39958, username: test, packet: SUBACK(Q0, R0, D0, PacketId=2, ReasonCodes=[0])
2025-10-31T14:16:35.216728+08:00 [debug] clientid: 603010000019, msg: insert_channel_info, peername: 36.19.65.183:39958, username: test
2025-10-31T14:16:35.216896+08:00 [debug] tag: MQTT, clientid: 603010000019, msg: mqtt_packet_sent, peername: 36.19.65.183:39958, username: test, packet: PUBLISH(Q0, R1, D0, Topic=/test/user/update, PacketId=undefined, Payload(text)={“did”:“603010000019”,“sdid”:“000000000001”,“productid”:“203010001”,“mt”:“update”,“mid”:“c31f0f5-0f8d-40bf-a0ea-3dde2637b186”,“ts”:1.76189e+12,“data”:{“pros”:{“010001”:0,“010011”:229.573,“010012”:0,“010013”:0,“010021”:0,“010022”:0,“010023”:0,“010100”:0,“010101”:0,“010102”:0,“010103”:0,“010112”:1,“010113”:1,“010114”:1,“010115”:1,“010116”:4.996,“010201”:31.92,“010202”:29.06,“010203”:30.04,“030010”:751586,“030020”:528326,“030030”:858875,“030040”:801815,“030050”:911704,“030060”:334553,“030070”:802951,“030080”:789188,“030000”:340286,“000003”:1,“000005”:0,“000002”:“000”}}})
2025-10-31T14:16:35.848227+08:00 [debug] tag: MQTT, clientid: 145060600691, msg: raw_bin_received, peername: 39.144.129.26:8279, username: test, size: 24, type: hex, bin: 8216000300112F746573742F757365722F75706461746500
2025-10-31T14:16:35.848610+08:00 [debug] tag: MQTT, clientid: 145060600691, msg: mqtt_packet_received, peername: 39.144.129.26:8279, username: test, packet: SUBSCRIBE(Q1, R0, D0, PacketId=3 TopicFilters=[/test/user/update(#{nl => 0,qos => 0,rap => 0,rh => 0})])
2025-10-31T14:16:35.848866+08:00 [debug] tag: SUBSCRIBE, clientid: 145060600691, msg: subscribe, peername: 39.144.129.26:8279, username: test, topic: /test/user/update, sub_id: <<“145060600691”>>, sub_opts: #{nl => 0,qos => 0,rap => 0,rh => 0,sub_props => #{}}
2025-10-31T14:16:35.849157+08:00 [debug] tag: MQTT, clientid: 145060600691, msg: mqtt_packet_sent, peername: 39.144.129.26:8279, username: test, packet: SUBACK(Q0, R0, D0, PacketId=3, ReasonCodes=[0])
2025-10-31T14:16:35.849424+08:00 [debug] clientid: 145060600691, msg: insert_channel_info, peername: 39.144.129.26:8279, username: test
2025-10-31T14:16:35.849770+08:00 [debug] tag: MQTT, clientid: 145060600691, msg: mqtt_packet_sent, peername: 39.144.129.26:8279, username: test, packet: PUBLISH(Q0, R1, D0, Topic=/test/user/update, PacketId=undefined, Payload(text)={“did”:“603010000019”,“sdid”:“000000000001”,“productid”:“203010001”,“mt”:“update”,“mid”:“c31f0f5-0f8d-40bf-a0ea-3dde2637b186”,“ts”:1.76189e+12,“data”:{“pros”:{“010001”:0,“010011”:229.573,“010012”:0,“010013”:0,“010021”:0,“010022”:0,“010023”:0,“010100”:0,“010101”:0,“010102”:0,“010103”:0,“010112”:1,“010113”:1,“010114”:1,“010115”:1,“010116”:4.996,“010201”:31.92,“010202”:29.06,“010203”:30.04,“030010”:751586,“030020”:528326,“030030”:858875,“030040”:801815,“030050”:911704,“030060”:334553,“030070”:802951,“030080”:789188,“030000”:340286,“000003”:1,“000005”:0,“000002”:“000”}}})
Trace里面的日志:
2025-10-31T14:16:35.848227+08:00 [debug] tag: MQTT, clientid: 145060600691, msg: raw_bin_received, peername: 39.144.129.26:8279, username: test, size: 24, type: hex, bin: 8216000300112F746573742F757365722F75706461746500
2025-10-31T14:16:35.848549+08:00 [MQTT] 145060600691@39.144.129.26:8279 msg: mqtt_packet_received, packet: SUBSCRIBE(Q1, R0, D0, PacketId=3 TopicFilters=[/test/user/update(#{nl => 0,qos => 0,rap => 0,rh => 0})]), username: test
2025-10-31T14:16:35.848822+08:00 [SUBSCRIBE] 145060600691@39.144.129.26:8279 msg: subscribe, sub_id: 145060600691, sub_opts: [nl: 0, qos: 0, rap: 0, rh: 0, sub_props: ], topic: /test/user/update, username: test
2025-10-31T14:16:35.849111+08:00 [MQTT] 145060600691@39.144.129.26:8279 msg: mqtt_packet_sent, packet: SUBACK(Q0, R0, D0, PacketId=3, ReasonCodes=[0]), username: test
2025-10-31T14:16:35.849424+08:00 [debug] clientid: 145060600691, msg: insert_channel_info, peername: 39.144.129.26:8279, username: test
2025-10-31T14:16:35.849688+08:00 [MQTT] 145060600691@39.144.129.26:8279 msg: mqtt_packet_sent, packet: PUBLISH(Q0, R1, D0, Topic=/test/user/update, PacketId=undefined, Payload(text)={“did”:“603010000019”,“sdid”:“000000000001”,“productid”:“203010001”,“mt”:“update”,“mid”:“c31f0f5-0f8d-40bf-a0ea-3dde2637b186”,“ts”:1.76189e+12,“data”:{“pros”:{“010001”:0,“010011”:229.573,“010012”:0,“010013”:0,“010021”:0,“010022”:0,“010023”:0,“010100”:0,“010101”:0,“010102”:0,“010103”:0,“010112”:1,“010113”:1,“010114”:1,“010115”:1,“010116”:4.996,“010201”:31.92,“010202”:29.06,“010203”:30.04,“030010”:751586,“030020”:528326,“030030”:858875,“030040”:801815,“030050”:911704,“030060”:334553,“030070”:802951,“030080”:789188,“030000”:340286,“000003”:1,“000005”:0,“000002”:“000”}}}), username: test
2025-10-31T14:17:10.835408+08:00 [debug] tag: MQTT, clientid: 145060600691, msg: raw_bin_received, peername: 39.144.129.26:8279, username: test, size: 24, type: hex, bin: 8216000400112F746573742F757365722F75706461746500
2025-10-31T14:17:10.835761+08:00 [MQTT] 145060600691@39.144.129.26:8279 msg: mqtt_packet_received, packet: SUBSCRIBE(Q1, R0, D0, PacketId=4 TopicFilters=[/test/user/update(#{nl => 0,qos => 0,rap => 0,rh => 0})]), username: test
2025-10-31T14:17:10.835979+08:00 [AUTHZ] 145060600691@39.144.129.26:8279 msg: authorization_module_ignore, action: SUBSCRIBE(Q0), authorize_type: client_info, module: emqx_authz_client_info, topic: /test/user/update, username: test
2025-10-31T14:17:10.836169+08:00 [AUTHZ] 145060600691@39.144.129.26:8279 msg: authorization_matched_allow, action: SUBSCRIBE(Q0), authorize_type: file, module: emqx_authz_file, topic: /test/user/update, username: test
2025-10-31T14:17:10.836343+08:00 [info] tag: AUTHZ, clientid: 145060600691, msg: authorization_permission_allowed, peername: 39.144.129.26:8279, username: test, topic: /test/user/update, action: SUBSCRIBE(Q0), source: file



