[20190115 18:56:42] [INFO] Set data dir to: /mnt/data/miio/
[20190115 18:56:42] [INFO] Set log level to: 2
[20190115 18:56:42] [INFO] Set TCP host to "ott.io.mi.com"
[20190115 18:56:42] [INFO] Set UDP host to "ot.io.mi.com"
[20190115 18:56:42] [INFO] OT smart conn sockfd: 8
[20190115 18:56:42] [INFO] OT agent listen fd: 10
[20190115 18:56:42] [INFO] BT conn listen fd: 11
[20190115 18:56:42] [INFO] Timer fd: 12, interval: 5000 ms
[20190115 18:56:42] [INFO] Sync timer fd: 13, interval: 0 ms
[20190115 18:56:42] [INFO] ACK timer fd: 14, timeout: 0 ms
[20190115 18:56:42] [INFO] pollfds[0]: 8
[20190115 18:56:42] [INFO] pollfds[1]: 10
[20190115 18:56:42] [INFO] pollfds[2]: 11
[20190115 18:56:42] [INFO] pollfds[3]: 12
[20190115 18:56:42] [INFO] pollfds[4]: 13
[20190115 18:56:42] [INFO] pollfds[5]: 14
[20190115 18:56:43] [INFO] OT agent listen accept sockfd: 15
[20190115 18:56:47] [WARNING] cmd_internal_request_dinfo:1124, ot_agent_internalfd: -1
[20190115 18:56:47] [WARNING] No helper script?
{"method":"_internal.helper_ready"}
[20190115 18:56:51] [INFO] OT agent listen accept sockfd: 16
[20190115 18:56:51] [INFO] OT agent listen accept sockfd: 16
[20190115 18:56:51] [INFO] STATE: (0)
[20190115 18:56:51] [INFO] OT agent internalfd closed: 16
Got _internal.request_dinfo
[20190115 18:56:51] [INFO] OT agent listen accept sockfd: 16
[20190115 18:56:51] [INFO] STATE: (0) -> STATE_DIDKEY_DONE
[20190115 18:56:51] [INFO] OT agent listen accept sockfd: 16
[20190115 18:56:51] [INFO] STATE: (3)
[20190115 18:56:51] [INFO] OT agent internalfd closed: 16
Got _internal.request_dtoken
[20190115 18:56:51] [INFO] OT agent listen accept sockfd: 16
[20190115 18:56:51] [INFO] STATE: (3) -> STATE_TOKEN_DONE
[20190115 18:56:51] [INFO] OT agent listen accept sockfd: 16
[20190115 18:56:51] [INFO] OT agent listen accept sockfd: 16
[20190115 18:56:51] [INFO] STATE: (4)
[20190115 18:56:51] [INFO] OT agent internalfd closed: 16
Got _internal.req_wifi_conf_status
[20190115 18:56:51] [INFO] OT agent listen accept sockfd: 16
[20190115 18:56:51] [INFO] STATE: (4) -> STATE_WIFI_STA_MODE
[20190115 18:56:51] [WARNING] error in trying to connect socket: Network is unreachable
[20190115 18:56:51] [WARNING] error in trying to connect socket: Network is unreachable
[20190115 18:56:51] [WARNING] no server detected.
[20190115 18:56:51] [WARNING] internet connection failed
[20190115 18:56:51] [WARNING] POLLNVAL | POLLHUP | POLLERR fd: pollfds[7]: 16, revents: 0x00000019
[20190115 18:56:51] [INFO] OT agent listen accept sockfd: 16
[20190115 18:56:51] [INFO] STATE: (6)
[20190115 18:56:52] [WARNING] error in trying to connect socket: Network is unreachable
[20190115 18:56:52] [WARNING] error in trying to connect socket: Network is unreachable
[20190115 18:56:52] [WARNING] no server detected.
[20190115 18:56:52] [WARNING] internet connection failed
[20190115 18:56:57] [INFO] Add UDP server into list, host ip: 203.0.113.1, port: 8053
[20190115 18:56:57] [INFO] Add TCP server into list, host ip: 203.0.113.1, port: 80
[20190115 18:56:57] [INFO] Connect to server ip: 203.0.113.1, port: 8053
[20190115 18:56:57] [INFO] OT cloud sockfd: 17
[20190115 18:56:57] [INFO] STATE: (6) -> STATE_CLOUD_TRYING
[20190115 18:56:57] [INFO] report_synctime
[20190115 18:56:57] [INFO] internet connection succeed
[20190115 18:56:57] [WARNING] POLLNVAL | POLLHUP | POLLERR fd: pollfds[8]: 17, revents: 0x00000008
[20190115 18:57:03] [INFO] OT protocol diagrams come...
[20190115 18:57:03] [INFO] Fill my DID
[20190115 18:57:03] [INFO] Fill my TIMESTAMP
[20190115 18:57:07] [INFO] Retry (1/-1), host ip: 203.0.113.1, port: 8053
[20190115 18:57:07] [INFO] OT client fd: 17
[20190115 18:57:07] [INFO] report_synctime
[20190115 18:57:07] [WARNING] POLLNVAL | POLLHUP | POLLERR fd: pollfds[8]: 17, revents: 0x00000008
[20190115 18:57:07] [INFO] OT protocol diagrams come...
[20190115 18:57:07] [INFO] handle_mobile_msg
[20190115 18:57:07] [INFO] Got miIO.wifi_assoc_state.
[20190115 18:57:07] [INFO] OT protocol diagrams come...
[20190115 18:57:07] [INFO] handle_mobile_msg
[20190115 18:57:07] [INFO] Got miIO.info.
[20190115 18:57:07] [WARNING] miIO.info fail
[20190115 18:57:07] [INFO] OT protocol diagrams come...
[20190115 18:57:07] [INFO] handle_mobile_msg
[20190115 18:57:07] [INFO] callback_queue_issue() id: 1294566309, to: mobile
[20190115 18:57:07] [INFO] OT protocol diagrams come...
[20190115 18:57:07] [INFO] handle_mobile_msg
[20190115 18:57:07] [INFO] callback_queue_issue() id: 434465991, to: mobile
[20190115 18:57:10] [INFO] OT protocol diagrams come...
[20190115 18:57:10] [INFO] handle_mobile_msg
[20190115 18:57:10] [INFO] callback_queue_issue() id: 2131703993, to: mobile
[20190115 18:57:10] [INFO] OT protocol diagrams come...
[20190115 18:57:10] [INFO] handle_mobile_msg
[20190115 18:57:10] [INFO] callback_queue_issue() id: 552499028, to: mobile
[20190115 18:57:10] [INFO] OT protocol diagrams come...
[20190115 18:57:10] [INFO] handle_mobile_msg
[20190115 18:57:10] [INFO] callback_queue_issue() id: 405299967, to: mobile
[20190115 18:57:10] [INFO] OT protocol diagrams come...
[20190115 18:57:10] [INFO] handle_mobile_msg
[20190115 18:57:10] [INFO] callback_queue_issue() id: 748676626, to: mobile
[20190115 18:57:10] [INFO] OT protocol diagrams come...
[20190115 18:57:10] [INFO] handle_mobile_msg
[20190115 18:57:10] [INFO] callback_queue_issue() id: 1651506744, to: mobile
[20190115 18:57:10] [INFO] OT protocol diagrams come...
[20190115 18:57:10] [INFO] handle_mobile_msg
[20190115 18:57:10] [INFO] callback_queue_issue() id: 765588450, to: mobile
[20190115 18:57:10] [INFO] OT protocol diagrams come...
[20190115 18:57:10] [INFO] handle_mobile_msg
[20190115 18:57:10] [INFO] callback_queue_issue() id: 237233579, to: mobile
[20190115 18:57:10] [INFO] OT protocol diagrams come...
[20190115 18:57:10] [INFO] handle_mobile_msg
[20190115 18:57:10] [INFO] callback_queue_issue() id: 929175788, to: mobile
[20190115 18:57:10] [INFO] OT protocol diagrams come...
[20190115 18:57:10] [INFO] handle_mobile_msg
[20190115 18:57:10] [INFO] callback_queue_issue() id: 859330176, to: mobile
[20190115 18:57:10] [INFO] OT protocol diagrams come...
[20190115 18:57:10] [INFO] handle_mobile_msg
[20190115 18:57:10] [INFO] callback_queue_issue() id: 2026200271, to: mobile
[20190115 18:57:10] [INFO] OT protocol diagrams come...
[20190115 18:57:10] [INFO] handle_mobile_msg
[20190115 18:57:10] [INFO] callback_queue_issue() id: 1059650254, to: mobile
[20190115 18:57:10] [INFO] OT protocol diagrams come...
[20190115 18:57:10] [INFO] handle_mobile_msg
[20190115 18:57:10] [INFO] callback_queue_issue() id: 2063658733, to: mobile
[20190115 18:57:13] [INFO] OT protocol diagrams come...
[20190115 18:57:13] [INFO] handle_mobile_msg
[20190115 18:57:13] [INFO] callback_queue_issue() id: 1793595592, to: mobile
[20190115 18:57:27] [INFO] Add TCP server into list, host ip: 203.0.113.1, port: 80
[20190115 18:57:27] [INFO] Retry (2/-1), host ip: 203.0.113.1, port: 80
[20190115 18:57:27] [WARNING] connect cloud sock (17) error: Operation now in progress
[20190115 18:57:27] [INFO] wait cloud sock (17) done
[20190115 18:57:27] [INFO] OT client fd: 17
[20190115 18:57:27] [INFO] report_synctime
[20190115 18:57:27] [WARNING] OT received (74), less than size of OT packet (21584).
[20190115 18:57:41] [WARNING] cloud socket not ready yet ...
[20190115 18:57:57] [INFO] Retry (3/-1), host ip: 203.0.113.1, port: 80
[20190115 18:57:57] [WARNING] connect cloud sock (17) error: Operation now in progress
[20190115 18:57:57] [INFO] wait cloud sock (17) done
[20190115 18:57:57] [INFO] OT client fd: 17
[20190115 18:57:57] [INFO] report_synctime
[20190115 18:57:57] [WARNING] OT received (74), less than size of OT packet (21584).
[20190115 18:58:37] [INFO] Add UDP server into list, host ip: 203.0.113.1, port: 8053
[20190115 18:58:37] [INFO] Retry (4/-1), host ip: 203.0.113.1, port: 8053
[20190115 18:58:37] [INFO] OT client fd: 17
[20190115 18:58:37] [INFO] report_synctime
[20190115 18:58:37] [WARNING] POLLNVAL | POLLHUP | POLLERR fd: pollfds[8]: 17, revents: 0x00000008
[20190115 18:58:41] [WARNING] cloud socket not ready yet ...
[20190115 18:59:27] [INFO] Retry (5/-1), host ip: 203.0.113.1, port: 8053
[20190115 18:59:27] [INFO] OT client fd: 17
[20190115 18:59:27] [INFO] report_synctime
[20190115 18:59:27] [WARNING] POLLNVAL | POLLHUP | POLLERR fd: pollfds[8]: 17, revents: 0x00000008
[20190115 18:59:41] [WARNING] cloud socket not ready yet ...
[20190115 19:00:27] [INFO] Add TCP server into list, host ip: 203.0.113.1, port: 80
[20190115 19:00:27] [INFO] Retry (6/-1), host ip: 203.0.113.1, port: 80
[20190115 19:00:27] [WARNING] connect cloud sock (17) error: Operation now in progress
[20190115 19:00:27] [INFO] wait cloud sock (17) done
[20190115 19:00:27] [INFO] OT client fd: 17
[20190115 19:00:27] [INFO] report_synctime
[20190115 19:00:27] [WARNING] OT received (74), less than size of OT packet (21584).
[20190115 19:00:41] [WARNING] cloud socket not ready yet ...
[20190115 19:01:37] [INFO] Retry (7/-1), host ip: 203.0.113.1, port: 80
[20190115 19:01:37] [WARNING] connect cloud sock (17) error: Operation now in progress
[20190115 19:01:38] [INFO] wait cloud sock (17) done
[20190115 19:01:38] [INFO] OT client fd: 17
[20190115 19:01:38] [INFO] report_synctime
[20190115 19:01:38] [WARNING] OT received (74), less than size of OT packet (21584).
[20190115 19:01:41] [WARNING] cloud socket not ready yet ...
[20190115 19:02:13] [INFO] OT protocol diagrams come...
[20190115 19:02:13] [INFO] Fill my DID
[20190115 19:02:13] [INFO] Fill my TIMESTAMP
[20190115 19:02:13] [INFO] OT protocol diagrams come...
[20190115 19:02:13] [INFO] handle_mobile_msg
[20190115 19:02:13] [INFO] callback_queue_issue() id: 1249844279, to: mobile