{"level":"warn","ts":"2026-09-18T05:23:14.906588Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T05:23:14.906689Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.52: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.52:2380","--initial-cluster=instance=https://162.253.55.52:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.52:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.52: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-09-18T05:23:14.906769Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T05:23:14.906781Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-09-18T05:23:14.906823Z","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-09-18T05:23:14.90755Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"]} {"level":"info","ts":"2026-09-18T05:23:14.90767Z","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":8,"max-cpu-available":8,"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.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.52: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-09-18T05:23:14.910586Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.56915ms"} {"level":"info","ts":"2026-09-18T05:23:14.915731Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b11e7fc5714be64b","cluster-id":"9349e4f32a52a90a"} {"level":"info","ts":"2026-09-18T05:23:14.915808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T05:23:14.915845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 0"} {"level":"info","ts":"2026-09-18T05:23:14.915854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b11e7fc5714be64b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T05:23:14.915863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 1"} {"level":"info","ts":"2026-09-18T05:23:14.9159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"warn","ts":"2026-09-18T05:23:14.921107Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T05:23:14.922409Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T05:23:14.923537Z","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-09-18T05:23:14.925809Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b11e7fc5714be64b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T05:23:14.927531Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b11e7fc5714be64b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T05:23:14.927676Z","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-09-18T05:23:14.927726Z","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-09-18T05:23:14.927736Z","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-09-18T05:23:14.93136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"info","ts":"2026-09-18T05:23:14.931485Z","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-09-18T05:23:14.93152Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","added-peer-id":"b11e7fc5714be64b","added-peer-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-09-18T05:23:14.931688Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b11e7fc5714be64b","initial-advertise-peer-urls":["https://162.253.55.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T05:23:14.931727Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T05:23:14.931823Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-09-18T05:23:14.931848Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-09-18T05:23:15.916623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T05:23:15.916681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T05:23:15.916722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgPreVoteResp from b11e7fc5714be64b at term 1"} {"level":"info","ts":"2026-09-18T05:23:15.916737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became candidate at term 2"} {"level":"info","ts":"2026-09-18T05:23:15.916743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgVoteResp from b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-09-18T05:23:15.916753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became leader at term 2"} {"level":"info","ts":"2026-09-18T05:23:15.916763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b11e7fc5714be64b elected leader b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-09-18T05:23:15.91796Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:23:15.918728Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T05:23:15.91874Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b11e7fc5714be64b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.52:2379]}","request-path":"/0/members/b11e7fc5714be64b/attributes","cluster-id":"9349e4f32a52a90a","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T05:23:15.918771Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T05:23:15.919109Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T05:23:15.919193Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T05:23:15.919264Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:23:15.919377Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:23:15.919405Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:23:15.921241Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-18T05:23:15.921442Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.52:2379"} {"level":"info","ts":"2026-09-18T05:23:18.598629Z","caller":"traceutil/trace.go:171","msg":"trace[1730068523] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"117.10956ms","start":"2026-09-18T05:23:18.48146Z","end":"2026-09-18T05:23:18.598569Z","steps":["trace[1730068523] 'process raft request' (duration: 73.234108ms)","trace[1730068523] 'compare' (duration: 43.671576ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:23:19.223987Z","caller":"traceutil/trace.go:171","msg":"trace[1360955410] transaction","detail":"{read_only:false; response_revision:91; number_of_response:1; }","duration":"101.380592ms","start":"2026-09-18T05:23:19.122554Z","end":"2026-09-18T05:23:19.223934Z","steps":["trace[1360955410] 'process raft request' (duration: 80.935887ms)","trace[1360955410] 'compare' (duration: 20.082125ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:23:21.732095Z","caller":"traceutil/trace.go:171","msg":"trace[2143649018] transaction","detail":"{read_only:false; response_revision:232; number_of_response:1; }","duration":"171.651118ms","start":"2026-09-18T05:23:21.560414Z","end":"2026-09-18T05:23:21.732065Z","steps":["trace[2143649018] 'process raft request' (duration: 171.485074ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:23:22.125364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.867962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:23:22.12552Z","caller":"traceutil/trace.go:171","msg":"trace[442322411] linearizableReadLoop","detail":"{readStateIndex:239; appliedIndex:238; }","duration":"359.265931ms","start":"2026-09-18T05:23:21.766228Z","end":"2026-09-18T05:23:22.125494Z","steps":["trace[442322411] 'read index received' (duration: 63.194756ms)","trace[442322411] 'applied index is now lower than readState.Index' (duration: 296.069065ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:23:22.125603Z","caller":"traceutil/trace.go:171","msg":"trace[1914921175] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"380.884848ms","start":"2026-09-18T05:23:21.744683Z","end":"2026-09-18T05:23:22.125568Z","steps":["trace[1914921175] 'process raft request' (duration: 84.862054ms)","trace[1914921175] 'compare' (duration: 294.626896ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:23:22.125706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.13753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T05:23:22.125752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:23:21.744662Z","time spent":"381.022131ms","remote":"127.0.0.1:57052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":203,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:23:22.125804Z","caller":"traceutil/trace.go:171","msg":"trace[407077348] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:233; }","duration":"232.350136ms","start":"2026-09-18T05:23:21.893429Z","end":"2026-09-18T05:23:22.125779Z","steps":["trace[407077348] 'agreement among raft nodes before linearized reading' (duration: 232.218532ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:23:22.125706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.470387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3708"} {"level":"info","ts":"2026-09-18T05:23:22.126004Z","caller":"traceutil/trace.go:171","msg":"trace[535040331] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:233; }","duration":"359.784246ms","start":"2026-09-18T05:23:21.766212Z","end":"2026-09-18T05:23:22.125996Z","steps":["trace[535040331] 'agreement among raft nodes before linearized reading' (duration: 359.383685ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:23:22.12604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:23:21.766145Z","time spent":"359.878178ms","remote":"127.0.0.1:57016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3732,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-09-18T05:23:22.398038Z","caller":"traceutil/trace.go:171","msg":"trace[2093671879] linearizableReadLoop","detail":"{readStateIndex:240; appliedIndex:239; }","duration":"265.099445ms","start":"2026-09-18T05:23:22.132909Z","end":"2026-09-18T05:23:22.398009Z","steps":["trace[2093671879] 'read index received' (duration: 256.076081ms)","trace[2093671879] 'applied index is now lower than readState.Index' (duration: 9.021924ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:23:22.39821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.276409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-09-18T05:23:22.398247Z","caller":"traceutil/trace.go:171","msg":"trace[230535751] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:234; }","duration":"265.368722ms","start":"2026-09-18T05:23:22.132867Z","end":"2026-09-18T05:23:22.398236Z","steps":["trace[230535751] 'agreement among raft nodes before linearized reading' (duration: 265.247549ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:23:22.398278Z","caller":"traceutil/trace.go:171","msg":"trace[1371294877] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"267.464929ms","start":"2026-09-18T05:23:22.130764Z","end":"2026-09-18T05:23:22.398229Z","steps":["trace[1371294877] 'process raft request' (duration: 258.217619ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:23:22.409299Z","caller":"traceutil/trace.go:171","msg":"trace[976298208] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"274.303905ms","start":"2026-09-18T05:23:22.134952Z","end":"2026-09-18T05:23:22.409256Z","steps":["trace[976298208] 'process raft request' (duration: 274.122061ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:00.678284Z","caller":"traceutil/trace.go:171","msg":"trace[1913168962] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"219.821267ms","start":"2026-09-18T05:24:00.458428Z","end":"2026-09-18T05:24:00.678249Z","steps":["trace[1913168962] 'process raft request' (duration: 219.337934ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:00.678429Z","caller":"traceutil/trace.go:171","msg":"trace[548014725] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"219.755545ms","start":"2026-09-18T05:24:00.45865Z","end":"2026-09-18T05:24:00.678405Z","steps":["trace[548014725] 'process raft request' (duration: 219.549909ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:00.678376Z","caller":"traceutil/trace.go:171","msg":"trace[313189730] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"219.844908ms","start":"2026-09-18T05:24:00.458502Z","end":"2026-09-18T05:24:00.678346Z","steps":["trace[313189730] 'process raft request' (duration: 219.592711ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:00.678588Z","caller":"traceutil/trace.go:171","msg":"trace[998912385] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"207.07271ms","start":"2026-09-18T05:24:00.471464Z","end":"2026-09-18T05:24:00.678536Z","steps":["trace[998912385] 'process raft request' (duration: 206.935146ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:00.678769Z","caller":"traceutil/trace.go:171","msg":"trace[1184714190] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"205.814767ms","start":"2026-09-18T05:24:00.472945Z","end":"2026-09-18T05:24:00.67876Z","steps":["trace[1184714190] 'process raft request' (duration: 205.57179ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:00.678916Z","caller":"traceutil/trace.go:171","msg":"trace[1029987472] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"203.788352ms","start":"2026-09-18T05:24:00.475112Z","end":"2026-09-18T05:24:00.678901Z","steps":["trace[1029987472] 'process raft request' (duration: 203.601717ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:00.679035Z","caller":"traceutil/trace.go:171","msg":"trace[1321976960] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"199.021112ms","start":"2026-09-18T05:24:00.480001Z","end":"2026-09-18T05:24:00.679022Z","steps":["trace[1321976960] 'process raft request' (duration: 198.851257ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:00.679215Z","caller":"traceutil/trace.go:171","msg":"trace[896329396] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"204.374997ms","start":"2026-09-18T05:24:00.474523Z","end":"2026-09-18T05:24:00.678898Z","steps":["trace[896329396] 'process raft request' (duration: 204.107019ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:00.679744Z","caller":"traceutil/trace.go:171","msg":"trace[293208535] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"188.989469ms","start":"2026-09-18T05:24:00.490741Z","end":"2026-09-18T05:24:00.67973Z","steps":["trace[293208535] 'process raft request' (duration: 188.598748ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:00.679886Z","caller":"traceutil/trace.go:171","msg":"trace[348077781] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"187.972942ms","start":"2026-09-18T05:24:00.491906Z","end":"2026-09-18T05:24:00.679879Z","steps":["trace[348077781] 'process raft request' (duration: 187.695664ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:00.67994Z","caller":"traceutil/trace.go:171","msg":"trace[480811146] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"157.202956ms","start":"2026-09-18T05:24:00.522731Z","end":"2026-09-18T05:24:00.679934Z","steps":["trace[480811146] 'process raft request' (duration: 157.068813ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:00.679975Z","caller":"traceutil/trace.go:171","msg":"trace[919742543] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:518; }","duration":"158.940834ms","start":"2026-09-18T05:24:00.520972Z","end":"2026-09-18T05:24:00.679913Z","steps":["trace[919742543] 'read index received' (duration: 93.237801ms)","trace[919742543] 'applied index is now lower than readState.Index' (duration: 65.701783ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:24:00.680126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.137859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:24:00.680152Z","caller":"traceutil/trace.go:171","msg":"trace[624402807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"159.211591ms","start":"2026-09-18T05:24:00.520934Z","end":"2026-09-18T05:24:00.680146Z","steps":["trace[624402807] 'agreement among raft nodes before linearized reading' (duration: 159.102458ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:07.111452Z","caller":"traceutil/trace.go:171","msg":"trace[1395835145] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"184.189068ms","start":"2026-09-18T05:24:06.927233Z","end":"2026-09-18T05:24:07.111422Z","steps":["trace[1395835145] 'process raft request' (duration: 183.543021ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:07.121198Z","caller":"traceutil/trace.go:171","msg":"trace[266477547] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"190.823818ms","start":"2026-09-18T05:24:06.930344Z","end":"2026-09-18T05:24:07.121168Z","steps":["trace[266477547] 'process raft request' (duration: 190.645434ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:07.121918Z","caller":"traceutil/trace.go:171","msg":"trace[1452393637] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"191.285142ms","start":"2026-09-18T05:24:06.930599Z","end":"2026-09-18T05:24:07.121884Z","steps":["trace[1452393637] 'process raft request' (duration: 190.525561ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:07.122287Z","caller":"traceutil/trace.go:171","msg":"trace[1929962355] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"191.407055ms","start":"2026-09-18T05:24:06.930868Z","end":"2026-09-18T05:24:07.122275Z","steps":["trace[1929962355] 'process raft request' (duration: 190.535791ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:07.34628Z","caller":"traceutil/trace.go:171","msg":"trace[1940771182] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"205.748045ms","start":"2026-09-18T05:24:07.140497Z","end":"2026-09-18T05:24:07.346245Z","steps":["trace[1940771182] 'process raft request' (duration: 205.490938ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:07.366231Z","caller":"traceutil/trace.go:171","msg":"trace[2070451021] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"180.54629ms","start":"2026-09-18T05:24:07.185653Z","end":"2026-09-18T05:24:07.366199Z","steps":["trace[2070451021] 'process raft request' (duration: 180.16706ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:07.829705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.980224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:24:07.829908Z","caller":"traceutil/trace.go:171","msg":"trace[889539461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"320.215181ms","start":"2026-09-18T05:24:07.509648Z","end":"2026-09-18T05:24:07.829863Z","steps":["trace[889539461] 'range keys from in-memory index tree' (duration: 319.894322ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:07.830329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:07.509624Z","time spent":"320.681634ms","remote":"127.0.0.1:56836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-18T05:24:08.064096Z","caller":"traceutil/trace.go:171","msg":"trace[641316740] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"238.508724ms","start":"2026-09-18T05:24:07.825562Z","end":"2026-09-18T05:24:08.064071Z","steps":["trace[641316740] 'process raft request' (duration: 237.943218ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:24.482018Z","caller":"traceutil/trace.go:171","msg":"trace[1323296735] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"244.235499ms","start":"2026-09-18T05:24:24.237756Z","end":"2026-09-18T05:24:24.481992Z","steps":["trace[1323296735] 'process raft request' (duration: 244.063384ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:24.485758Z","caller":"traceutil/trace.go:171","msg":"trace[42056554] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"198.297992ms","start":"2026-09-18T05:24:24.287403Z","end":"2026-09-18T05:24:24.485701Z","steps":["trace[42056554] 'process raft request' (duration: 197.771117ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:04.932912Z","caller":"traceutil/trace.go:171","msg":"trace[714468408] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"113.276768ms","start":"2026-09-18T05:25:04.819592Z","end":"2026-09-18T05:25:04.932869Z","steps":["trace[714468408] 'process raft request' (duration: 112.99714ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:04.933037Z","caller":"traceutil/trace.go:171","msg":"trace[1898466805] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"105.748994ms","start":"2026-09-18T05:25:04.827256Z","end":"2026-09-18T05:25:04.933005Z","steps":["trace[1898466805] 'process raft request' (duration: 105.550978ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:04.932939Z","caller":"traceutil/trace.go:171","msg":"trace[474557554] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"109.783874ms","start":"2026-09-18T05:25:04.823126Z","end":"2026-09-18T05:25:04.93291Z","steps":["trace[474557554] 'process raft request' (duration: 109.61795ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:07.831612Z","caller":"traceutil/trace.go:171","msg":"trace[1091599302] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1108; }","duration":"150.366564ms","start":"2026-09-18T05:25:07.681221Z","end":"2026-09-18T05:25:07.831588Z","steps":["trace[1091599302] 'read index received' (duration: 133.393434ms)","trace[1091599302] 'applied index is now lower than readState.Index' (duration: 16.97211ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:25:07.831768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.529019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-18T05:25:07.831806Z","caller":"traceutil/trace.go:171","msg":"trace[1106764173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1072; }","duration":"150.615971ms","start":"2026-09-18T05:25:07.681177Z","end":"2026-09-18T05:25:07.831793Z","steps":["trace[1106764173] 'agreement among raft nodes before linearized reading' (duration: 150.490437ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:07.831786Z","caller":"traceutil/trace.go:171","msg":"trace[1191424690] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"190.696396ms","start":"2026-09-18T05:25:07.641028Z","end":"2026-09-18T05:25:07.831724Z","steps":["trace[1191424690] 'process raft request' (duration: 173.609233ms)","trace[1191424690] 'compare' (duration: 16.836286ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:25:17.678649Z","caller":"traceutil/trace.go:171","msg":"trace[245305331] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"120.066592ms","start":"2026-09-18T05:25:17.558545Z","end":"2026-09-18T05:25:17.678612Z","steps":["trace[245305331] 'process raft request' (duration: 119.828496ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:20.613043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.02788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:25:20.613047Z","caller":"traceutil/trace.go:171","msg":"trace[2101339123] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"132.267663ms","start":"2026-09-18T05:25:20.480726Z","end":"2026-09-18T05:25:20.612994Z","steps":["trace[2101339123] 'process raft request' (duration: 87.952233ms)","trace[2101339123] 'compare' (duration: 44.163576ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:25:20.613112Z","caller":"traceutil/trace.go:171","msg":"trace[541635972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1115; }","duration":"100.105922ms","start":"2026-09-18T05:25:20.51299Z","end":"2026-09-18T05:25:20.613096Z","steps":["trace[541635972] 'agreement among raft nodes before linearized reading' (duration: 99.987089ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:22.0851Z","caller":"traceutil/trace.go:171","msg":"trace[554652869] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"122.998021ms","start":"2026-09-18T05:25:21.962082Z","end":"2026-09-18T05:25:22.08508Z","steps":["trace[554652869] 'process raft request' (duration: 122.778155ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:22.238476Z","caller":"traceutil/trace.go:171","msg":"trace[804712831] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"146.229972ms","start":"2026-09-18T05:25:22.092214Z","end":"2026-09-18T05:25:22.238444Z","steps":["trace[804712831] 'process raft request' (duration: 114.677197ms)","trace[804712831] 'compare' (duration: 30.984189ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:25:23.766684Z","caller":"traceutil/trace.go:171","msg":"trace[1699264471] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"395.035682ms","start":"2026-09-18T05:25:23.371634Z","end":"2026-09-18T05:25:23.76667Z","steps":["trace[1699264471] 'process raft request' (duration: 394.822896ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:23.766771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.118601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:25:23.766835Z","caller":"traceutil/trace.go:171","msg":"trace[1369334156] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1122; }","duration":"255.458151ms","start":"2026-09-18T05:25:23.511348Z","end":"2026-09-18T05:25:23.766806Z","steps":["trace[1369334156] 'agreement among raft nodes before linearized reading' (duration: 255.368268ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:23.767011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:23.37161Z","time spent":"395.148144ms","remote":"127.0.0.1:57086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:25:23.766658Z","caller":"traceutil/trace.go:171","msg":"trace[1761949731] linearizableReadLoop","detail":"{readStateIndex:1162; appliedIndex:1161; }","duration":"254.990718ms","start":"2026-09-18T05:25:23.511645Z","end":"2026-09-18T05:25:23.766635Z","steps":["trace[1761949731] 'read index received' (duration: 254.68703ms)","trace[1761949731] 'applied index is now lower than readState.Index' (duration: 302.958µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:25:25.487452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.559870497s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-09-18T05:25:25.487346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.052928474s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:25:25.487571Z","caller":"traceutil/trace.go:171","msg":"trace[1544268134] linearizableReadLoop","detail":"{readStateIndex:1163; appliedIndex:1162; }","duration":"1.678787688s","start":"2026-09-18T05:25:23.808759Z","end":"2026-09-18T05:25:25.487547Z","steps":["trace[1544268134] 'read index received' (duration: 118.762567ms)","trace[1544268134] 'applied index is now lower than readState.Index' (duration: 1.560023261s)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:25:25.48764Z","caller":"traceutil/trace.go:171","msg":"trace[1243005851] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1122; }","duration":"1.053234833s","start":"2026-09-18T05:25:24.434371Z","end":"2026-09-18T05:25:25.487606Z","steps":["trace[1243005851] 'range keys from in-memory index tree' (duration: 1.052908194s)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:25.487698Z","caller":"traceutil/trace.go:171","msg":"trace[1414997661] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"1.716049248s","start":"2026-09-18T05:25:23.771631Z","end":"2026-09-18T05:25:25.48768Z","steps":["trace[1414997661] 'process raft request' (duration: 155.824241ms)","trace[1414997661] 'compare' (duration: 1.558243243s)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:25:25.48778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:23.771608Z","time spent":"1.71613022s","remote":"127.0.0.1:57086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:25:25.487976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.67921867s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-18T05:25:25.488079Z","caller":"traceutil/trace.go:171","msg":"trace[1724649640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1123; }","duration":"1.679376934s","start":"2026-09-18T05:25:23.808677Z","end":"2026-09-18T05:25:25.488054Z","steps":["trace[1724649640] 'agreement among raft nodes before linearized reading' (duration: 1.679103896s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:25.488135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:23.808657Z","time spent":"1.679468497s","remote":"127.0.0.1:57086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-18T05:25:25.52833Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.43595073s","expected-duration":"1s"} {"level":"info","ts":"2026-09-18T05:25:25.52891Z","caller":"traceutil/trace.go:171","msg":"trace[585031787] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"1.436613008s","start":"2026-09-18T05:25:24.092258Z","end":"2026-09-18T05:25:25.528871Z","steps":["trace[585031787] 'process raft request' (duration: 1.436437073s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:25.529141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:24.092235Z","time spent":"1.436786612s","remote":"127.0.0.1:57086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:25:25.543132Z","caller":"traceutil/trace.go:171","msg":"trace[1597145561] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"331.748746ms","start":"2026-09-18T05:25:25.211345Z","end":"2026-09-18T05:25:25.543093Z","steps":["trace[1597145561] 'process raft request' (duration: 331.483729ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:25.543364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:25.211303Z","time spent":"331.933761ms","remote":"127.0.0.1:57086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:25:25.543458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.988254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T05:25:25.543532Z","caller":"traceutil/trace.go:171","msg":"trace[2084184836] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1126; }","duration":"521.071126ms","start":"2026-09-18T05:25:25.022445Z","end":"2026-09-18T05:25:25.543516Z","steps":["trace[2084184836] 'agreement among raft nodes before linearized reading' (duration: 520.882271ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:25.543528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.912932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-18T05:25:25.54358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:25.02232Z","time spent":"521.24803ms","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-09-18T05:25:25.543627Z","caller":"traceutil/trace.go:171","msg":"trace[1538878795] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1126; }","duration":"844.082866ms","start":"2026-09-18T05:25:24.699522Z","end":"2026-09-18T05:25:25.543605Z","steps":["trace[1538878795] 'agreement among raft nodes before linearized reading' (duration: 843.85181ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:25.543684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:24.699488Z","time spent":"844.185059ms","remote":"127.0.0.1:57052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-09-18T05:25:25.543885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.44741559s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T05:25:25.543953Z","caller":"traceutil/trace.go:171","msg":"trace[860140973] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1126; }","duration":"1.447481812s","start":"2026-09-18T05:25:24.09645Z","end":"2026-09-18T05:25:25.543932Z","steps":["trace[860140973] 'agreement among raft nodes before linearized reading' (duration: 1.44737953s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:25.544008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:24.096345Z","time spent":"1.447647076s","remote":"127.0.0.1:57182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-09-18T05:25:25.54437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.30081992s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-18T05:25:25.544474Z","caller":"traceutil/trace.go:171","msg":"trace[379140457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1126; }","duration":"1.300968944s","start":"2026-09-18T05:25:24.243487Z","end":"2026-09-18T05:25:25.544456Z","steps":["trace[379140457] 'agreement among raft nodes before linearized reading' (duration: 1.300728047s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:25.544528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:24.243451Z","time spent":"1.301058916s","remote":"127.0.0.1:57086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-18T05:25:25.54438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.432347052s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-18T05:25:25.544689Z","caller":"traceutil/trace.go:171","msg":"trace[210065028] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1126; }","duration":"1.432715113s","start":"2026-09-18T05:25:24.111959Z","end":"2026-09-18T05:25:25.544675Z","steps":["trace[210065028] 'agreement among raft nodes before linearized reading' (duration: 1.432326672s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:25.544737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:24.11193Z","time spent":"1.432788165s","remote":"127.0.0.1:57086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-18T05:25:25.748791Z","caller":"traceutil/trace.go:171","msg":"trace[1998204497] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"197.222143ms","start":"2026-09-18T05:25:25.551545Z","end":"2026-09-18T05:25:25.748767Z","steps":["trace[1998204497] 'process raft request' (duration: 197.073959ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:25.764027Z","caller":"traceutil/trace.go:171","msg":"trace[1132341596] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"212.213238ms","start":"2026-09-18T05:25:25.551766Z","end":"2026-09-18T05:25:25.76398Z","steps":["trace[1132341596] 'process raft request' (duration: 212.013703ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:25.785255Z","caller":"traceutil/trace.go:171","msg":"trace[472260836] linearizableReadLoop","detail":"{readStateIndex:1169; appliedIndex:1166; }","duration":"156.775357ms","start":"2026-09-18T05:25:25.628442Z","end":"2026-09-18T05:25:25.785217Z","steps":["trace[472260836] 'read index received' (duration: 120.275868ms)","trace[472260836] 'applied index is now lower than readState.Index' (duration: 36.497449ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:25:25.785552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.068865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T05:25:25.785654Z","caller":"traceutil/trace.go:171","msg":"trace[147366926] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1128; }","duration":"157.25918ms","start":"2026-09-18T05:25:25.62837Z","end":"2026-09-18T05:25:25.785629Z","steps":["trace[147366926] 'agreement among raft nodes before linearized reading' (duration: 157.012563ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:27.876095Z","caller":"traceutil/trace.go:171","msg":"trace[1220328353] linearizableReadLoop","detail":"{readStateIndex:1174; appliedIndex:1173; }","duration":"108.648613ms","start":"2026-09-18T05:25:27.767416Z","end":"2026-09-18T05:25:27.876065Z","steps":["trace[1220328353] 'read index received' (duration: 108.396407ms)","trace[1220328353] 'applied index is now lower than readState.Index' (duration: 246.426µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:25:27.87622Z","caller":"traceutil/trace.go:171","msg":"trace[1113383546] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"119.160888ms","start":"2026-09-18T05:25:27.757005Z","end":"2026-09-18T05:25:27.876166Z","steps":["trace[1113383546] 'process raft request' (duration: 118.85749ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:27.876391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.956182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-18T05:25:27.876497Z","caller":"traceutil/trace.go:171","msg":"trace[1282275644] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1133; }","duration":"109.130297ms","start":"2026-09-18T05:25:27.767336Z","end":"2026-09-18T05:25:27.876467Z","steps":["trace[1282275644] 'agreement among raft nodes before linearized reading' (duration: 108.839139ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:29.718833Z","caller":"traceutil/trace.go:171","msg":"trace[1071570308] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"153.312343ms","start":"2026-09-18T05:25:29.565474Z","end":"2026-09-18T05:25:29.718787Z","steps":["trace[1071570308] 'process raft request' (duration: 153.167009ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:30.57618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.299199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:25:30.576306Z","caller":"traceutil/trace.go:171","msg":"trace[1516196219] linearizableReadLoop","detail":"{readStateIndex:1178; appliedIndex:1177; }","duration":"979.471773ms","start":"2026-09-18T05:25:29.596812Z","end":"2026-09-18T05:25:30.576284Z","steps":["trace[1516196219] 'read index received' (duration: 122.064797ms)","trace[1516196219] 'applied index is now lower than readState.Index' (duration: 857.404296ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:25:30.576339Z","caller":"traceutil/trace.go:171","msg":"trace[1480967836] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"1.008806358s","start":"2026-09-18T05:25:29.567503Z","end":"2026-09-18T05:25:30.576309Z","steps":["trace[1480967836] 'process raft request' (duration: 445.216171ms)","trace[1480967836] 'compare' (duration: 562.844227ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:25:30.576483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:29.567473Z","time spent":"1.008951981s","remote":"127.0.0.1:57086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:25:30.576945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.131371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-18T05:25:30.577003Z","caller":"traceutil/trace.go:171","msg":"trace[432020129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1137; }","duration":"980.238475ms","start":"2026-09-18T05:25:29.59675Z","end":"2026-09-18T05:25:30.576988Z","steps":["trace[432020129] 'agreement among raft nodes before linearized reading' (duration: 979.620228ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:30.577295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:29.59672Z","time spent":"980.557884ms","remote":"127.0.0.1:57086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-18T05:25:31.563594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.143494ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:25:31.563895Z","caller":"traceutil/trace.go:171","msg":"trace[951199215] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"982.547608ms","start":"2026-09-18T05:25:30.581322Z","end":"2026-09-18T05:25:31.56387Z","steps":["trace[951199215] 'process raft request' (duration: 982.426434ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:31.563972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:30.581287Z","time spent":"982.65164ms","remote":"127.0.0.1:57086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:25:31.564103Z","caller":"traceutil/trace.go:171","msg":"trace[67469694] linearizableReadLoop","detail":"{readStateIndex:1179; appliedIndex:1178; }","duration":"987.712247ms","start":"2026-09-18T05:25:30.576339Z","end":"2026-09-18T05:25:31.564051Z","steps":["trace[67469694] 'read index received' (duration: 317.001977ms)","trace[67469694] 'applied index is now lower than readState.Index' (duration: 670.70629ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:25:31.564372Z","caller":"traceutil/trace.go:171","msg":"trace[1516495228] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"1.427260434s","start":"2026-09-18T05:25:30.137098Z","end":"2026-09-18T05:25:31.564359Z","steps":["trace[1516495228] 'process raft request' (duration: 756.229616ms)","trace[1516495228] 'compare' (duration: 669.59633ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:25:31.564382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.113259118s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.52\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-09-18T05:25:31.56446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:30.137051Z","time spent":"1.427377907s","remote":"127.0.0.1:57086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:25:31.564461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.646400981s","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-09-18T05:25:31.564508Z","caller":"traceutil/trace.go:171","msg":"trace[1570713122] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1139; }","duration":"1.646512964s","start":"2026-09-18T05:25:29.917983Z","end":"2026-09-18T05:25:31.564496Z","steps":["trace[1570713122] 'agreement among raft nodes before linearized reading' (duration: 1.646256697s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:31.564502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05353373s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:25:31.564541Z","caller":"traceutil/trace.go:171","msg":"trace[1709851497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1139; }","duration":"1.053603082s","start":"2026-09-18T05:25:30.510931Z","end":"2026-09-18T05:25:31.564534Z","steps":["trace[1709851497] 'agreement among raft nodes before linearized reading' (duration: 1.05355226s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:31.564528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.683188156s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-09-18T05:25:31.56455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:29.91796Z","time spent":"1.646581486s","remote":"127.0.0.1:57086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-18T05:25:31.564563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:30.510912Z","time spent":"1.053645913s","remote":"127.0.0.1:56832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-18T05:25:31.56457Z","caller":"traceutil/trace.go:171","msg":"trace[128004911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1139; }","duration":"1.68329187s","start":"2026-09-18T05:25:29.881266Z","end":"2026-09-18T05:25:31.564558Z","steps":["trace[128004911] 'agreement among raft nodes before linearized reading' (duration: 1.683170136s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:31.564559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.412872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T05:25:31.564603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:29.881234Z","time spent":"1.683362742s","remote":"127.0.0.1:57086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-18T05:25:31.56465Z","caller":"traceutil/trace.go:171","msg":"trace[499235434] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1139; }","duration":"780.558145ms","start":"2026-09-18T05:25:30.784067Z","end":"2026-09-18T05:25:31.564625Z","steps":["trace[499235434] 'agreement among raft nodes before linearized reading' (duration: 780.408961ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:31.564462Z","caller":"traceutil/trace.go:171","msg":"trace[1625284767] range","detail":"{range_begin:/registry/masterleases/162.253.55.52; range_end:; response_count:1; response_revision:1139; }","duration":"1.11335198s","start":"2026-09-18T05:25:30.451095Z","end":"2026-09-18T05:25:31.564447Z","steps":["trace[1625284767] 'agreement among raft nodes before linearized reading' (duration: 1.113108374s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:31.564695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:30.784042Z","time spent":"780.644137ms","remote":"127.0.0.1:57064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-09-18T05:25:31.564712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.26427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:25:31.566064Z","caller":"traceutil/trace.go:171","msg":"trace[152734105] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1139; }","duration":"560.681949ms","start":"2026-09-18T05:25:31.005363Z","end":"2026-09-18T05:25:31.566045Z","steps":["trace[152734105] 'agreement among raft nodes before linearized reading' (duration: 559.313282ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:31.566121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:31.005334Z","time spent":"560.770202ms","remote":"127.0.0.1:46880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-09-18T05:25:31.564713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:30.451041Z","time spent":"1.113663459s","remote":"127.0.0.1:56870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.52\" "} {"level":"warn","ts":"2026-09-18T05:25:31.564799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.664670395s","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-09-18T05:25:31.566347Z","caller":"traceutil/trace.go:171","msg":"trace[2005926617] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1139; }","duration":"1.666264429s","start":"2026-09-18T05:25:29.900065Z","end":"2026-09-18T05:25:31.56633Z","steps":["trace[2005926617] 'agreement among raft nodes before linearized reading' (duration: 1.664614914s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:31.566455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:29.900036Z","time spent":"1.666396643s","remote":"127.0.0.1:57086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-18T05:25:32.125453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.657597ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-09-18T05:25:32.125666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:31.567351Z","time spent":"558.310895ms","remote":"127.0.0.1:56870","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-09-18T05:25:32.92837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.475306ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:25:32.928782Z","caller":"traceutil/trace.go:171","msg":"trace[345604920] linearizableReadLoop","detail":"{readStateIndex:1184; appliedIndex:1180; }","duration":"1.353426075s","start":"2026-09-18T05:25:31.575331Z","end":"2026-09-18T05:25:32.928757Z","steps":["trace[345604920] 'read index received' (duration: 219.418424ms)","trace[345604920] 'applied index is now lower than readState.Index' (duration: 1.134006021s)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:25:32.9289Z","caller":"traceutil/trace.go:171","msg":"trace[1870086427] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"1.356906178s","start":"2026-09-18T05:25:31.571977Z","end":"2026-09-18T05:25:32.928883Z","steps":["trace[1870086427] 'process raft request' (duration: 1.356662291s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:32.928995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:31.571959Z","time spent":"1.356982171s","remote":"127.0.0.1:57086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:25:32.928913Z","caller":"traceutil/trace.go:171","msg":"trace[327299413] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"801.815471ms","start":"2026-09-18T05:25:32.127081Z","end":"2026-09-18T05:25:32.928896Z","steps":["trace[327299413] 'process raft request' (duration: 801.627576ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:32.929244Z","caller":"traceutil/trace.go:171","msg":"trace[424073363] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"1.358633665s","start":"2026-09-18T05:25:31.570544Z","end":"2026-09-18T05:25:32.929178Z","steps":["trace[424073363] 'process raft request' (duration: 953.227613ms)","trace[424073363] 'compare' (duration: 404.311622ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:25:32.929476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:32.12704Z","time spent":"802.233663ms","remote":"127.0.0.1:56870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:25:32.928827Z","caller":"traceutil/trace.go:171","msg":"trace[1605811447] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"1.358032229s","start":"2026-09-18T05:25:31.570733Z","end":"2026-09-18T05:25:32.928765Z","steps":["trace[1605811447] 'process raft request' (duration: 1.357808823s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:32.929486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:31.570517Z","time spent":"1.358834361s","remote":"127.0.0.1:57086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:25:32.929703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:31.570726Z","time spent":"1.358934834s","remote":"127.0.0.1:57086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:25:32.929217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.353884817s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:25:32.929888Z","caller":"traceutil/trace.go:171","msg":"trace[1310810282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1143; }","duration":"1.354579676s","start":"2026-09-18T05:25:31.575286Z","end":"2026-09-18T05:25:32.929866Z","steps":["trace[1310810282] 'agreement among raft nodes before linearized reading' (duration: 1.353855016s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:32.929999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:31.575273Z","time spent":"1.35468512s","remote":"127.0.0.1:56832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-18T05:25:33.014466Z","caller":"traceutil/trace.go:171","msg":"trace[1024629733] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"431.796626ms","start":"2026-09-18T05:25:32.58264Z","end":"2026-09-18T05:25:33.014437Z","steps":["trace[1024629733] 'process raft request' (duration: 431.637022ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:33.014646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:32.582607Z","time spent":"431.9398ms","remote":"127.0.0.1:57086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:25:33.015095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.292319818s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-18T05:25:33.015131Z","caller":"traceutil/trace.go:171","msg":"trace[1214486621] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1144; }","duration":"1.29239702s","start":"2026-09-18T05:25:31.722726Z","end":"2026-09-18T05:25:33.015123Z","steps":["trace[1214486621] 'agreement among raft nodes before linearized reading' (duration: 1.292274457s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:33.015151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:25:31.722705Z","time spent":"1.292442402s","remote":"127.0.0.1:57086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-18T05:25:33.015583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.173668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:25:33.015748Z","caller":"traceutil/trace.go:171","msg":"trace[1390986100] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1144; }","duration":"140.9677ms","start":"2026-09-18T05:25:32.874758Z","end":"2026-09-18T05:25:33.015725Z","steps":["trace[1390986100] 'agreement among raft nodes before linearized reading' (duration: 140.183668ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:33.157585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.249716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:25:33.157686Z","caller":"traceutil/trace.go:171","msg":"trace[849404528] linearizableReadLoop","detail":"{readStateIndex:1187; appliedIndex:1186; }","duration":"135.120311ms","start":"2026-09-18T05:25:33.022552Z","end":"2026-09-18T05:25:33.157672Z","steps":["trace[849404528] 'read index received' (duration: 17.610897ms)","trace[849404528] 'applied index is now lower than readState.Index' (duration: 117.508464ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:25:33.157739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.188832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-18T05:25:33.157752Z","caller":"traceutil/trace.go:171","msg":"trace[1095877875] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1145; }","duration":"135.233993ms","start":"2026-09-18T05:25:33.022513Z","end":"2026-09-18T05:25:33.157747Z","steps":["trace[1095877875] 'agreement among raft nodes before linearized reading' (duration: 135.192162ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:33.157804Z","caller":"traceutil/trace.go:171","msg":"trace[225559178] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"135.287505ms","start":"2026-09-18T05:25:33.022492Z","end":"2026-09-18T05:25:33.15778Z","steps":["trace[225559178] 'process raft request' (duration: 17.72925ms)","trace[225559178] 'compare' (duration: 117.096222ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:25:33.371607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.915495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:25:33.37173Z","caller":"traceutil/trace.go:171","msg":"trace[1776792551] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1145; }","duration":"104.118301ms","start":"2026-09-18T05:25:33.267578Z","end":"2026-09-18T05:25:33.371696Z","steps":["trace[1776792551] 'count revisions from in-memory index tree' (duration: 103.785762ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:33.962084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.221667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:25:33.962188Z","caller":"traceutil/trace.go:171","msg":"trace[333472051] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1147; }","duration":"206.431272ms","start":"2026-09-18T05:25:33.755735Z","end":"2026-09-18T05:25:33.962166Z","steps":["trace[333472051] 'count revisions from in-memory index tree' (duration: 206.111453ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:34.752952Z","caller":"traceutil/trace.go:171","msg":"trace[1695856321] linearizableReadLoop","detail":"{readStateIndex:1192; appliedIndex:1191; }","duration":"101.004656ms","start":"2026-09-18T05:25:34.651918Z","end":"2026-09-18T05:25:34.752922Z","steps":["trace[1695856321] 'read index received' (duration: 100.828422ms)","trace[1695856321] 'applied index is now lower than readState.Index' (duration: 174.624µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:25:34.753004Z","caller":"traceutil/trace.go:171","msg":"trace[204545864] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"113.577016ms","start":"2026-09-18T05:25:34.639382Z","end":"2026-09-18T05:25:34.752959Z","steps":["trace[204545864] 'process raft request' (duration: 113.365031ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:34.753234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.285924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5330"} {"level":"info","ts":"2026-09-18T05:25:34.753274Z","caller":"traceutil/trace.go:171","msg":"trace[881067322] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1149; }","duration":"101.404136ms","start":"2026-09-18T05:25:34.65186Z","end":"2026-09-18T05:25:34.753264Z","steps":["trace[881067322] 'agreement among raft nodes before linearized reading' (duration: 101.1782ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:35.005513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.089551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:25:35.005634Z","caller":"traceutil/trace.go:171","msg":"trace[210502590] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1150; }","duration":"100.272286ms","start":"2026-09-18T05:25:34.905331Z","end":"2026-09-18T05:25:35.005604Z","steps":["trace[210502590] 'range keys from in-memory index tree' (duration: 99.944897ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:35.165995Z","caller":"traceutil/trace.go:171","msg":"trace[501889110] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"154.940527ms","start":"2026-09-18T05:25:35.011027Z","end":"2026-09-18T05:25:35.165968Z","steps":["trace[501889110] 'process raft request' (duration: 147.358332ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:35.166198Z","caller":"traceutil/trace.go:171","msg":"trace[383459842] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"154.913756ms","start":"2026-09-18T05:25:35.011271Z","end":"2026-09-18T05:25:35.166185Z","steps":["trace[383459842] 'process raft request' (duration: 154.042152ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:35.166377Z","caller":"traceutil/trace.go:171","msg":"trace[1633697566] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"145.335627ms","start":"2026-09-18T05:25:35.021029Z","end":"2026-09-18T05:25:35.166364Z","steps":["trace[1633697566] 'process raft request' (duration: 144.34577ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:45.087639Z","caller":"traceutil/trace.go:171","msg":"trace[564791065] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"296.866812ms","start":"2026-09-18T05:25:44.790736Z","end":"2026-09-18T05:25:45.087602Z","steps":["trace[564791065] 'process raft request' (duration: 296.581904ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:45.323819Z","caller":"traceutil/trace.go:171","msg":"trace[1369291148] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"230.850714ms","start":"2026-09-18T05:25:45.092931Z","end":"2026-09-18T05:25:45.323781Z","steps":["trace[1369291148] 'process raft request' (duration: 229.738134ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:25:45.326739Z","caller":"traceutil/trace.go:171","msg":"trace[965633405] linearizableReadLoop","detail":"{readStateIndex:1289; appliedIndex:1287; }","duration":"102.855606ms","start":"2026-09-18T05:25:45.223865Z","end":"2026-09-18T05:25:45.326721Z","steps":["trace[965633405] 'read index received' (duration: 99.675111ms)","trace[965633405] 'applied index is now lower than readState.Index' (duration: 3.179575ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:25:45.3269Z","caller":"traceutil/trace.go:171","msg":"trace[41056593] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"116.957849ms","start":"2026-09-18T05:25:45.209916Z","end":"2026-09-18T05:25:45.326874Z","steps":["trace[41056593] 'process raft request' (duration: 116.682771ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:25:45.327098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.198266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-18T05:25:45.327166Z","caller":"traceutil/trace.go:171","msg":"trace[1087446396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1244; }","duration":"103.322049ms","start":"2026-09-18T05:25:45.223826Z","end":"2026-09-18T05:25:45.327148Z","steps":["trace[1087446396] 'agreement among raft nodes before linearized reading' (duration: 103.076642ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:26:33.759497Z","caller":"traceutil/trace.go:171","msg":"trace[1586944834] transaction","detail":"{read_only:false; response_revision:1468; number_of_response:1; }","duration":"105.181529ms","start":"2026-09-18T05:26:33.654243Z","end":"2026-09-18T05:26:33.759424Z","steps":["trace[1586944834] 'process raft request' (duration: 104.936983ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:26:33.868173Z","caller":"traceutil/trace.go:171","msg":"trace[439177717] transaction","detail":"{read_only:false; response_revision:1470; number_of_response:1; }","duration":"103.722909ms","start":"2026-09-18T05:26:33.764423Z","end":"2026-09-18T05:26:33.868146Z","steps":["trace[439177717] 'process raft request' (duration: 78.095346ms)","trace[439177717] 'compare' (duration: 25.269925ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:26:34.15768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.723332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-18T05:26:34.157775Z","caller":"traceutil/trace.go:171","msg":"trace[878305499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1470; }","duration":"171.885767ms","start":"2026-09-18T05:26:33.985866Z","end":"2026-09-18T05:26:34.157751Z","steps":["trace[878305499] 'range keys from in-memory index tree' (duration: 171.433375ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:30:07.293398Z","caller":"traceutil/trace.go:171","msg":"trace[1479899127] linearizableReadLoop","detail":"{readStateIndex:2541; appliedIndex:2540; }","duration":"152.300513ms","start":"2026-09-18T05:30:07.141067Z","end":"2026-09-18T05:30:07.293367Z","steps":["trace[1479899127] 'read index received' (duration: 133.414919ms)","trace[1479899127] 'applied index is now lower than readState.Index' (duration: 18.884554ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:30:07.293565Z","caller":"traceutil/trace.go:171","msg":"trace[959193528] transaction","detail":"{read_only:false; response_revision:2443; number_of_response:1; }","duration":"194.920531ms","start":"2026-09-18T05:30:07.098588Z","end":"2026-09-18T05:30:07.293509Z","steps":["trace[959193528] 'process raft request' (duration: 175.853802ms)","trace[959193528] 'compare' (duration: 18.783861ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:30:07.293744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.655062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-18T05:30:07.293789Z","caller":"traceutil/trace.go:171","msg":"trace[973291081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2443; }","duration":"152.760436ms","start":"2026-09-18T05:30:07.141016Z","end":"2026-09-18T05:30:07.293776Z","steps":["trace[973291081] 'agreement among raft nodes before linearized reading' (duration: 152.572781ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:30:07.293837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.824898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-18T05:30:07.293907Z","caller":"traceutil/trace.go:171","msg":"trace[941270245] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2443; }","duration":"131.958931ms","start":"2026-09-18T05:30:07.161931Z","end":"2026-09-18T05:30:07.29389Z","steps":["trace[941270245] 'agreement among raft nodes before linearized reading' (duration: 131.699594ms)"],"step_count":1}