{"level":"warn","ts":"2026-04-09T02:03:00.208402Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T02:03:00.208488Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.110:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.110:2380","--initial-cluster=instance=https://162.253.55.110:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.110:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.110:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-09T02:03:00.208549Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T02:03:00.20856Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-04-09T02:03:00.208577Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-09T02:03:00.209214Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"]} {"level":"info","ts":"2026-04-09T02:03:00.209296Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.110:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-09T02:03:00.212668Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.155367ms"} {"level":"info","ts":"2026-04-09T02:03:00.220797Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f52f30d5714009d5","cluster-id":"650981a7a383c714"} {"level":"info","ts":"2026-04-09T02:03:00.220874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T02:03:00.220895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 0"} {"level":"info","ts":"2026-04-09T02:03:00.220907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f52f30d5714009d5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T02:03:00.220918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 1"} {"level":"info","ts":"2026-04-09T02:03:00.220954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"warn","ts":"2026-04-09T02:03:00.230375Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T02:03:00.2321Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T02:03:00.234271Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-09T02:03:00.236545Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f52f30d5714009d5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T02:03:00.236809Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f52f30d5714009d5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T02:03:00.236957Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-09T02:03:00.23703Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-09T02:03:00.23706Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-09T02:03:00.242633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"info","ts":"2026-04-09T02:03:00.242809Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","added-peer-id":"f52f30d5714009d5","added-peer-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-04-09T02:03:00.245085Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-09T02:03:00.245243Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-04-09T02:03:00.245298Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-04-09T02:03:00.245447Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f52f30d5714009d5","initial-advertise-peer-urls":["https://162.253.55.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T02:03:00.245532Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T02:03:00.821299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T02:03:00.821384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T02:03:00.821449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgPreVoteResp from f52f30d5714009d5 at term 1"} {"level":"info","ts":"2026-04-09T02:03:00.821477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became candidate at term 2"} {"level":"info","ts":"2026-04-09T02:03:00.821493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgVoteResp from f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-04-09T02:03:00.82156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became leader at term 2"} {"level":"info","ts":"2026-04-09T02:03:00.821582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f52f30d5714009d5 elected leader f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-04-09T02:03:00.82255Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:03:00.82357Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T02:03:00.823575Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f52f30d5714009d5","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.110:2379]}","request-path":"/0/members/f52f30d5714009d5/attributes","cluster-id":"650981a7a383c714","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T02:03:00.823638Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T02:03:00.823953Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:03:00.824132Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:03:00.824069Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T02:03:00.824199Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:03:00.824208Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T02:03:00.825206Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-09T02:03:00.82621Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.110:2379"} {"level":"info","ts":"2026-04-09T02:03:23.963727Z","caller":"traceutil/trace.go:171","msg":"trace[1958121003] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"105.0657ms","start":"2026-04-09T02:03:23.858586Z","end":"2026-04-09T02:03:23.963652Z","steps":["trace[1958121003] 'process raft request' (duration: 35.574587ms)","trace[1958121003] 'compare' (duration: 69.259228ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:03:31.98606Z","caller":"traceutil/trace.go:171","msg":"trace[188908538] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"103.287138ms","start":"2026-04-09T02:03:31.882733Z","end":"2026-04-09T02:03:31.986021Z","steps":["trace[188908538] 'process raft request' (duration: 92.981572ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:38.503426Z","caller":"traceutil/trace.go:171","msg":"trace[1947450349] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"106.250128ms","start":"2026-04-09T02:03:38.397132Z","end":"2026-04-09T02:03:38.503382Z","steps":["trace[1947450349] 'read index received' (duration: 102.97037ms)","trace[1947450349] 'applied index is now lower than readState.Index' (duration: 3.278508ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:03:38.503536Z","caller":"traceutil/trace.go:171","msg":"trace[1831338505] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"303.957211ms","start":"2026-04-09T02:03:38.199522Z","end":"2026-04-09T02:03:38.503479Z","steps":["trace[1831338505] 'process raft request' (duration: 301.694777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:38.503803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.466703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:03:38.503902Z","caller":"traceutil/trace.go:171","msg":"trace[527633795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"106.828942ms","start":"2026-04-09T02:03:38.397058Z","end":"2026-04-09T02:03:38.503887Z","steps":["trace[527633795] 'agreement among raft nodes before linearized reading' (duration: 106.490794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:38.504611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:38.199478Z","time spent":"304.381921ms","remote":"127.0.0.1:48738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:03:38.845584Z","caller":"traceutil/trace.go:171","msg":"trace[611886201] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"134.614913ms","start":"2026-04-09T02:03:38.710933Z","end":"2026-04-09T02:03:38.845548Z","steps":["trace[611886201] 'read index received' (duration: 133.073817ms)","trace[611886201] 'applied index is now lower than readState.Index' (duration: 1.539716ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:03:38.84564Z","caller":"traceutil/trace.go:171","msg":"trace[1196354614] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"283.190618ms","start":"2026-04-09T02:03:38.562408Z","end":"2026-04-09T02:03:38.845598Z","steps":["trace[1196354614] 'process raft request' (duration: 281.659372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:38.845946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.987901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-09T02:03:38.84607Z","caller":"traceutil/trace.go:171","msg":"trace[942471600] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"135.172516ms","start":"2026-04-09T02:03:38.710867Z","end":"2026-04-09T02:03:38.846039Z","steps":["trace[942471600] 'agreement among raft nodes before linearized reading' (duration: 134.796938ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:39.031635Z","caller":"traceutil/trace.go:171","msg":"trace[176598154] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"180.447724ms","start":"2026-04-09T02:03:38.85114Z","end":"2026-04-09T02:03:39.031588Z","steps":["trace[176598154] 'process raft request' (duration: 161.175545ms)","trace[176598154] 'compare' (duration: 19.025313ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:03:39.031659Z","caller":"traceutil/trace.go:171","msg":"trace[451088314] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"177.685798ms","start":"2026-04-09T02:03:38.853946Z","end":"2026-04-09T02:03:39.031631Z","steps":["trace[451088314] 'read index received' (duration: 158.536432ms)","trace[451088314] 'applied index is now lower than readState.Index' (duration: 19.147776ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:03:39.031653Z","caller":"traceutil/trace.go:171","msg":"trace[1371162696] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"169.965555ms","start":"2026-04-09T02:03:38.861657Z","end":"2026-04-09T02:03:39.031622Z","steps":["trace[1371162696] 'process raft request' (duration: 169.884723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:39.031898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.932264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-09T02:03:39.031939Z","caller":"traceutil/trace.go:171","msg":"trace[1924422074] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:587; }","duration":"178.017226ms","start":"2026-04-09T02:03:38.853907Z","end":"2026-04-09T02:03:39.031925Z","steps":["trace[1924422074] 'agreement among raft nodes before linearized reading' (duration: 177.897653ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:08.913751Z","caller":"traceutil/trace.go:171","msg":"trace[1396642577] transaction","detail":"{read_only:false; response_revision:1805; number_of_response:1; }","duration":"261.655819ms","start":"2026-04-09T02:05:08.652055Z","end":"2026-04-09T02:05:08.91371Z","steps":["trace[1396642577] 'process raft request' (duration: 261.439484ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:09.014471Z","caller":"traceutil/trace.go:171","msg":"trace[549799024] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"258.592367ms","start":"2026-04-09T02:05:08.755852Z","end":"2026-04-09T02:05:09.014444Z","steps":["trace[549799024] 'process raft request' (duration: 258.411013ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:09.014699Z","caller":"traceutil/trace.go:171","msg":"trace[1125268049] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"221.748263ms","start":"2026-04-09T02:05:08.792926Z","end":"2026-04-09T02:05:09.014674Z","steps":["trace[1125268049] 'process raft request' (duration: 221.472387ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:09.080415Z","caller":"traceutil/trace.go:171","msg":"trace[468658752] linearizableReadLoop","detail":"{readStateIndex:1879; appliedIndex:1878; }","duration":"295.185393ms","start":"2026-04-09T02:05:08.785184Z","end":"2026-04-09T02:05:09.080369Z","steps":["trace[468658752] 'read index received' (duration: 128.507735ms)","trace[468658752] 'applied index is now lower than readState.Index' (duration: 166.675338ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:05:09.080781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.574582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-09T02:05:09.080876Z","caller":"traceutil/trace.go:171","msg":"trace[567502715] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1807; }","duration":"295.737376ms","start":"2026-04-09T02:05:08.785116Z","end":"2026-04-09T02:05:09.080853Z","steps":["trace[567502715] 'agreement among raft nodes before linearized reading' (duration: 295.444839ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:09.246285Z","caller":"traceutil/trace.go:171","msg":"trace[147092916] linearizableReadLoop","detail":"{readStateIndex:1882; appliedIndex:1881; }","duration":"155.963604ms","start":"2026-04-09T02:05:09.090292Z","end":"2026-04-09T02:05:09.246255Z","steps":["trace[147092916] 'read index received' (duration: 155.171496ms)","trace[147092916] 'applied index is now lower than readState.Index' (duration: 790.218µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:05:09.246395Z","caller":"traceutil/trace.go:171","msg":"trace[1171680405] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"160.296818ms","start":"2026-04-09T02:05:09.086055Z","end":"2026-04-09T02:05:09.246352Z","steps":["trace[1171680405] 'process raft request' (duration: 159.439608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:09.246769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.450677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ebd7b962-4e5a-4f2c-8461-b6463be2f1f9\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-09T02:05:09.246824Z","caller":"traceutil/trace.go:171","msg":"trace[1469179130] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ebd7b962-4e5a-4f2c-8461-b6463be2f1f9; range_end:; response_count:1; response_revision:1808; }","duration":"156.557659ms","start":"2026-04-09T02:05:09.090251Z","end":"2026-04-09T02:05:09.246809Z","steps":["trace[1469179130] 'agreement among raft nodes before linearized reading' (duration: 156.109378ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:09.366043Z","caller":"traceutil/trace.go:171","msg":"trace[314841202] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"109.080184ms","start":"2026-04-09T02:05:09.256893Z","end":"2026-04-09T02:05:09.365973Z","steps":["trace[314841202] 'process raft request' (duration: 108.47209ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:49.268235Z","caller":"traceutil/trace.go:171","msg":"trace[72027716] transaction","detail":"{read_only:false; response_revision:2084; number_of_response:1; }","duration":"112.044002ms","start":"2026-04-09T02:05:49.156161Z","end":"2026-04-09T02:05:49.268205Z","steps":["trace[72027716] 'process raft request' (duration: 111.838307ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:49.280806Z","caller":"traceutil/trace.go:171","msg":"trace[218335679] transaction","detail":"{read_only:false; response_revision:2085; number_of_response:1; }","duration":"122.664642ms","start":"2026-04-09T02:05:49.15811Z","end":"2026-04-09T02:05:49.280775Z","steps":["trace[218335679] 'process raft request' (duration: 122.427966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:49.511182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.186352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:49.511317Z","caller":"traceutil/trace.go:171","msg":"trace[502320838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2086; }","duration":"114.409538ms","start":"2026-04-09T02:05:49.396883Z","end":"2026-04-09T02:05:49.511293Z","steps":["trace[502320838] 'range keys from in-memory index tree' (duration: 113.972808ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:00.882481Z","caller":"traceutil/trace.go:171","msg":"trace[1726219045] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"141.545711ms","start":"2026-04-09T02:06:00.740909Z","end":"2026-04-09T02:06:00.882455Z","steps":["trace[1726219045] 'process raft request' (duration: 141.408998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:01.692347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.847399ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:06:01.692544Z","caller":"traceutil/trace.go:171","msg":"trace[1361253192] linearizableReadLoop","detail":"{readStateIndex:2232; appliedIndex:2230; }","duration":"296.332144ms","start":"2026-04-09T02:06:01.39619Z","end":"2026-04-09T02:06:01.692522Z","steps":["trace[1361253192] 'read index received' (duration: 8.02396ms)","trace[1361253192] 'applied index is now lower than readState.Index' (duration: 288.306694ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:01.692569Z","caller":"traceutil/trace.go:171","msg":"trace[2124085141] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"350.049445ms","start":"2026-04-09T02:06:01.34248Z","end":"2026-04-09T02:06:01.692529Z","steps":["trace[2124085141] 'process raft request' (duration: 182.730735ms)","trace[2124085141] 'compare' (duration: 166.664455ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:01.69268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.467027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:06:01.69275Z","caller":"traceutil/trace.go:171","msg":"trace[1150875834] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2148; }","duration":"296.614491ms","start":"2026-04-09T02:06:01.396109Z","end":"2026-04-09T02:06:01.692724Z","steps":["trace[1150875834] 'agreement among raft nodes before linearized reading' (duration: 296.473127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:01.692759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:01.342456Z","time spent":"350.201538ms","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:06:01.841157Z","caller":"traceutil/trace.go:171","msg":"trace[1586060456] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"142.749379ms","start":"2026-04-09T02:06:01.698357Z","end":"2026-04-09T02:06:01.841106Z","steps":["trace[1586060456] 'process raft request' (duration: 142.493303ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:24.840417Z","caller":"traceutil/trace.go:171","msg":"trace[1203832868] transaction","detail":"{read_only:false; response_revision:2793; number_of_response:1; }","duration":"123.22507ms","start":"2026-04-09T02:07:24.717158Z","end":"2026-04-09T02:07:24.840383Z","steps":["trace[1203832868] 'process raft request' (duration: 122.977945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:11:25.948439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.592145ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:11:25.949258Z","caller":"traceutil/trace.go:171","msg":"trace[1604598847] transaction","detail":"{read_only:false; response_revision:4985; number_of_response:1; }","duration":"217.930617ms","start":"2026-04-09T02:11:25.731294Z","end":"2026-04-09T02:11:25.949225Z","steps":["trace[1604598847] 'process raft request' (duration: 67.452721ms)","trace[1604598847] 'compare' (duration: 149.443532ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:11:25.949512Z","caller":"traceutil/trace.go:171","msg":"trace[99548465] transaction","detail":"{read_only:false; response_revision:4986; number_of_response:1; }","duration":"162.39472ms","start":"2026-04-09T02:11:25.787093Z","end":"2026-04-09T02:11:25.949488Z","steps":["trace[99548465] 'process raft request' (duration: 161.482059ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:11:26.077599Z","caller":"traceutil/trace.go:171","msg":"trace[1835393280] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"123.234384ms","start":"2026-04-09T02:11:25.954319Z","end":"2026-04-09T02:11:26.077553Z","steps":["trace[1835393280] 'process raft request' (duration: 121.257717ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:11:26.077299Z","caller":"traceutil/trace.go:171","msg":"trace[2044328306] transaction","detail":"{read_only:false; response_revision:4988; number_of_response:1; }","duration":"123.745416ms","start":"2026-04-09T02:11:25.953517Z","end":"2026-04-09T02:11:26.077262Z","steps":["trace[2044328306] 'process raft request' (duration: 121.884972ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:11:37.394865Z","caller":"traceutil/trace.go:171","msg":"trace[999747178] transaction","detail":"{read_only:false; response_revision:5211; number_of_response:1; }","duration":"123.593313ms","start":"2026-04-09T02:11:37.27125Z","end":"2026-04-09T02:11:37.394844Z","steps":["trace[999747178] 'process raft request' (duration: 123.550862ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:11:37.39516Z","caller":"traceutil/trace.go:171","msg":"trace[1272806586] transaction","detail":"{read_only:false; response_revision:5210; number_of_response:1; }","duration":"179.94211ms","start":"2026-04-09T02:11:37.215209Z","end":"2026-04-09T02:11:37.395151Z","steps":["trace[1272806586] 'process raft request' (duration: 96.905605ms)","trace[1272806586] 'compare' (duration: 82.558713ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:11:37.395485Z","caller":"traceutil/trace.go:171","msg":"trace[565560993] linearizableReadLoop","detail":"{readStateIndex:5409; appliedIndex:5408; }","duration":"127.24284ms","start":"2026-04-09T02:11:37.26819Z","end":"2026-04-09T02:11:37.395433Z","steps":["trace[565560993] 'read index received' (duration: 43.894358ms)","trace[565560993] 'applied index is now lower than readState.Index' (duration: 83.339632ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:11:37.395746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.490966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T02:11:37.395791Z","caller":"traceutil/trace.go:171","msg":"trace[869340155] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5211; }","duration":"127.632019ms","start":"2026-04-09T02:11:37.268146Z","end":"2026-04-09T02:11:37.395778Z","steps":["trace[869340155] 'agreement among raft nodes before linearized reading' (duration: 127.441215ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:11:37.585419Z","caller":"traceutil/trace.go:171","msg":"trace[1939104518] transaction","detail":"{read_only:false; response_revision:5215; number_of_response:1; }","duration":"136.225625ms","start":"2026-04-09T02:11:37.449163Z","end":"2026-04-09T02:11:37.585389Z","steps":["trace[1939104518] 'process raft request' (duration: 57.540055ms)","trace[1939104518] 'compare' (duration: 78.557607ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:11:37.862273Z","caller":"traceutil/trace.go:171","msg":"trace[1425711925] transaction","detail":"{read_only:false; response_revision:5220; number_of_response:1; }","duration":"201.323539ms","start":"2026-04-09T02:11:37.660932Z","end":"2026-04-09T02:11:37.862255Z","steps":["trace[1425711925] 'process raft request' (duration: 201.217586ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:11:37.865241Z","caller":"traceutil/trace.go:171","msg":"trace[1609744340] linearizableReadLoop","detail":"{readStateIndex:5420; appliedIndex:5418; }","duration":"197.60422ms","start":"2026-04-09T02:11:37.667627Z","end":"2026-04-09T02:11:37.865231Z","steps":["trace[1609744340] 'read index received' (duration: 194.525496ms)","trace[1609744340] 'applied index is now lower than readState.Index' (duration: 3.078074ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:11:37.865356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.702832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:11:37.865412Z","caller":"traceutil/trace.go:171","msg":"trace[923238195] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:5221; }","duration":"197.798065ms","start":"2026-04-09T02:11:37.6676Z","end":"2026-04-09T02:11:37.865398Z","steps":["trace[923238195] 'agreement among raft nodes before linearized reading' (duration: 197.694972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:11:37.865433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.384187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-09T02:11:37.86545Z","caller":"traceutil/trace.go:171","msg":"trace[817494219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5221; }","duration":"195.403408ms","start":"2026-04-09T02:11:37.670041Z","end":"2026-04-09T02:11:37.865444Z","steps":["trace[817494219] 'agreement among raft nodes before linearized reading' (duration: 195.331766ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:11:37.865525Z","caller":"traceutil/trace.go:171","msg":"trace[1425421569] transaction","detail":"{read_only:false; response_revision:5221; number_of_response:1; }","duration":"202.907247ms","start":"2026-04-09T02:11:37.662508Z","end":"2026-04-09T02:11:37.865415Z","steps":["trace[1425421569] 'process raft request' (duration: 202.658101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:11:37.865568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.94049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8xcjq\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-09T02:11:37.865582Z","caller":"traceutil/trace.go:171","msg":"trace[1438989672] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8xcjq; range_end:; response_count:1; response_revision:5221; }","duration":"185.955261ms","start":"2026-04-09T02:11:37.679622Z","end":"2026-04-09T02:11:37.865577Z","steps":["trace[1438989672] 'agreement among raft nodes before linearized reading' (duration: 185.90504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:11:37.86567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.21985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-09T02:11:37.865686Z","caller":"traceutil/trace.go:171","msg":"trace[282720968] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5221; }","duration":"189.26502ms","start":"2026-04-09T02:11:37.676418Z","end":"2026-04-09T02:11:37.865683Z","steps":["trace[282720968] 'agreement among raft nodes before linearized reading' (duration: 189.221929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:11:37.865759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.612924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-09T02:11:37.865798Z","caller":"traceutil/trace.go:171","msg":"trace[1072934390] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5221; }","duration":"193.665135ms","start":"2026-04-09T02:11:37.672123Z","end":"2026-04-09T02:11:37.865788Z","steps":["trace[1072934390] 'agreement among raft nodes before linearized reading' (duration: 193.567982ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:11:45.666666Z","caller":"traceutil/trace.go:171","msg":"trace[1031753663] transaction","detail":"{read_only:false; response_revision:5388; number_of_response:1; }","duration":"128.851913ms","start":"2026-04-09T02:11:45.537765Z","end":"2026-04-09T02:11:45.666617Z","steps":["trace[1031753663] 'process raft request' (duration: 115.978667ms)","trace[1031753663] 'compare' (duration: 12.649741ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:11:45.974094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.98558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:11:45.974164Z","caller":"traceutil/trace.go:171","msg":"trace[948364167] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:5389; }","duration":"151.119333ms","start":"2026-04-09T02:11:45.823029Z","end":"2026-04-09T02:11:45.974149Z","steps":["trace[948364167] 'count revisions from in-memory index tree' (duration: 150.871616ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:11:56.79825Z","caller":"traceutil/trace.go:171","msg":"trace[1871464295] transaction","detail":"{read_only:false; response_revision:5543; number_of_response:1; }","duration":"102.883685ms","start":"2026-04-09T02:11:56.695336Z","end":"2026-04-09T02:11:56.798219Z","steps":["trace[1871464295] 'process raft request' (duration: 102.68818ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:13:00.850994Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2991} {"level":"info","ts":"2026-04-09T02:13:00.923618Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2991,"took":"71.159444ms","hash":2833091612,"current-db-size-bytes":57901056,"current-db-size":"58 MB","current-db-size-in-use-bytes":33587200,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-09T02:13:00.923681Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2833091612,"revision":2991,"compact-revision":-1} {"level":"info","ts":"2026-04-09T02:17:50.871647Z","caller":"traceutil/trace.go:171","msg":"trace[56176986] linearizableReadLoop","detail":"{readStateIndex:8515; appliedIndex:8514; }","duration":"124.719748ms","start":"2026-04-09T02:17:50.746894Z","end":"2026-04-09T02:17:50.871614Z","steps":["trace[56176986] 'read index received' (duration: 124.424961ms)","trace[56176986] 'applied index is now lower than readState.Index' (duration: 293.807µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:17:50.871887Z","caller":"traceutil/trace.go:171","msg":"trace[217883933] transaction","detail":"{read_only:false; response_revision:8225; number_of_response:1; }","duration":"342.131593ms","start":"2026-04-09T02:17:50.529542Z","end":"2026-04-09T02:17:50.871674Z","steps":["trace[217883933] 'process raft request' (duration: 341.862917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:17:50.871925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.975924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T02:17:50.872178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:17:50.529509Z","time spent":"342.534553ms","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:17:50.872294Z","caller":"traceutil/trace.go:171","msg":"trace[1347274461] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8225; }","duration":"125.365743ms","start":"2026-04-09T02:17:50.746832Z","end":"2026-04-09T02:17:50.872198Z","steps":["trace[1347274461] 'agreement among raft nodes before linearized reading' (duration: 124.945103ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:18:00.858352Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6084} {"level":"info","ts":"2026-04-09T02:18:00.959505Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6084,"took":"99.670094ms","hash":2448638864,"current-db-size-bytes":57901056,"current-db-size":"58 MB","current-db-size-in-use-bytes":23523328,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-09T02:18:00.959594Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2448638864,"revision":6084,"compact-revision":2991} {"level":"info","ts":"2026-04-09T02:18:02.214065Z","caller":"traceutil/trace.go:171","msg":"trace[1206504547] transaction","detail":"{read_only:false; response_revision:8284; number_of_response:1; }","duration":"234.725396ms","start":"2026-04-09T02:18:01.979311Z","end":"2026-04-09T02:18:02.214036Z","steps":["trace[1206504547] 'process raft request' (duration: 174.15535ms)","trace[1206504547] 'compare' (duration: 60.071895ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:18:02.456298Z","caller":"traceutil/trace.go:171","msg":"trace[1154953879] linearizableReadLoop","detail":"{readStateIndex:8580; appliedIndex:8579; }","duration":"231.416538ms","start":"2026-04-09T02:18:02.224848Z","end":"2026-04-09T02:18:02.456264Z","steps":["trace[1154953879] 'read index received' (duration: 223.684835ms)","trace[1154953879] 'applied index is now lower than readState.Index' (duration: 7.730073ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:18:02.45662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.660013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-09T02:18:02.456664Z","caller":"traceutil/trace.go:171","msg":"trace[166537126] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:8286; }","duration":"231.763836ms","start":"2026-04-09T02:18:02.224888Z","end":"2026-04-09T02:18:02.456652Z","steps":["trace[166537126] 'agreement among raft nodes before linearized reading' (duration: 231.563731ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:18:02.456676Z","caller":"traceutil/trace.go:171","msg":"trace[1620087296] transaction","detail":"{read_only:false; response_revision:8286; number_of_response:1; }","duration":"235.475384ms","start":"2026-04-09T02:18:02.221168Z","end":"2026-04-09T02:18:02.456643Z","steps":["trace[1620087296] 'process raft request' (duration: 227.448005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:18:02.456772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.380167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8xcjq\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-04-09T02:18:02.456789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.892689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:123167"} {"level":"info","ts":"2026-04-09T02:18:02.456869Z","caller":"traceutil/trace.go:171","msg":"trace[1677430821] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8xcjq; range_end:; response_count:1; response_revision:8286; }","duration":"169.513759ms","start":"2026-04-09T02:18:02.287334Z","end":"2026-04-09T02:18:02.456848Z","steps":["trace[1677430821] 'agreement among raft nodes before linearized reading' (duration: 169.278534ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:18:02.456899Z","caller":"traceutil/trace.go:171","msg":"trace[132929102] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:8286; }","duration":"232.041912ms","start":"2026-04-09T02:18:02.224833Z","end":"2026-04-09T02:18:02.456875Z","steps":["trace[132929102] 'agreement among raft nodes before linearized reading' (duration: 231.606562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:18:31.614138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.310015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:18:31.614239Z","caller":"traceutil/trace.go:171","msg":"trace[1066263336] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8493; }","duration":"217.463928ms","start":"2026-04-09T02:18:31.396754Z","end":"2026-04-09T02:18:31.614218Z","steps":["trace[1066263336] 'range keys from in-memory index tree' (duration: 217.180902ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:18:44.76697Z","caller":"traceutil/trace.go:171","msg":"trace[996637189] transaction","detail":"{read_only:false; response_revision:8606; number_of_response:1; }","duration":"303.12748ms","start":"2026-04-09T02:18:44.46381Z","end":"2026-04-09T02:18:44.766937Z","steps":["trace[996637189] 'process raft request' (duration: 302.939895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:18:44.767154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:18:44.463777Z","time spent":"303.287764ms","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:18:45.545843Z","caller":"traceutil/trace.go:171","msg":"trace[1209370568] transaction","detail":"{read_only:false; response_revision:8613; number_of_response:1; }","duration":"206.514609ms","start":"2026-04-09T02:18:45.339289Z","end":"2026-04-09T02:18:45.545803Z","steps":["trace[1209370568] 'process raft request' (duration: 161.499751ms)","trace[1209370568] 'compare' (duration: 44.729161ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:18:45.5459Z","caller":"traceutil/trace.go:171","msg":"trace[847432367] linearizableReadLoop","detail":"{readStateIndex:8921; appliedIndex:8920; }","duration":"149.345202ms","start":"2026-04-09T02:18:45.396528Z","end":"2026-04-09T02:18:45.545873Z","steps":["trace[847432367] 'read index received' (duration: 104.398107ms)","trace[847432367] 'applied index is now lower than readState.Index' (duration: 44.944645ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:18:45.546021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.465745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:18:45.545902Z","caller":"traceutil/trace.go:171","msg":"trace[2129022986] transaction","detail":"{read_only:false; response_revision:8614; number_of_response:1; }","duration":"149.068805ms","start":"2026-04-09T02:18:45.39681Z","end":"2026-04-09T02:18:45.545879Z","steps":["trace[2129022986] 'process raft request' (duration: 148.962863ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:18:45.546081Z","caller":"traceutil/trace.go:171","msg":"trace[1364985482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8614; }","duration":"149.573317ms","start":"2026-04-09T02:18:45.396492Z","end":"2026-04-09T02:18:45.546065Z","steps":["trace[1364985482] 'agreement among raft nodes before linearized reading' (duration: 149.460364ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:19:26.864497Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002455.wal"} {"level":"info","ts":"2026-04-09T02:20:42.959369Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f52f30d5714009d5","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-09T02:20:42.965565Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-09T02:20:42.965725Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-09T02:23:00.865485Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8276} {"level":"info","ts":"2026-04-09T02:23:00.943347Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8276,"took":"75.348072ms","hash":3546872989,"current-db-size-bytes":57901056,"current-db-size":"58 MB","current-db-size-in-use-bytes":24866816,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-09T02:23:00.943422Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3546872989,"revision":8276,"compact-revision":6084} {"level":"info","ts":"2026-04-09T02:28:00.881614Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10669} {"level":"info","ts":"2026-04-09T02:28:00.961767Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10669,"took":"77.458767ms","hash":1040039879,"current-db-size-bytes":57901056,"current-db-size":"58 MB","current-db-size-in-use-bytes":21045248,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-09T02:28:00.962385Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1040039879,"revision":10669,"compact-revision":8276}