{"level":"warn","ts":"2026-01-31T12:42:42.071846Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-31T12:42:42.073438Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.206: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.206:2380","--initial-cluster=instance=https://162.253.55.206:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.206:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.206: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-01-31T12:42:42.073599Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-31T12:42:42.073615Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.206:2380"]} {"level":"info","ts":"2026-01-31T12:42:42.073686Z","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-01-31T12:42:42.074872Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"]} {"level":"info","ts":"2026-01-31T12:42:42.075591Z","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":2,"max-cpu-available":2,"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.206:2380"],"listen-peer-urls":["https://162.253.55.206:2380"],"advertise-client-urls":["https://162.253.55.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.206: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-01-31T12:42:42.081001Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.138662ms"} {"level":"info","ts":"2026-01-31T12:42:42.107075Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cfc9fec265e3384a","cluster-id":"9866fe2b0d49ed88"} {"level":"info","ts":"2026-01-31T12:42:42.107257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=()"} {"level":"info","ts":"2026-01-31T12:42:42.10728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became follower at term 0"} {"level":"info","ts":"2026-01-31T12:42:42.107358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cfc9fec265e3384a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-31T12:42:42.107425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became follower at term 1"} {"level":"info","ts":"2026-01-31T12:42:42.107553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=(14972778547056425034)"} {"level":"warn","ts":"2026-01-31T12:42:42.114752Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-31T12:42:42.115985Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-31T12:42:42.117312Z","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-01-31T12:42:42.119699Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cfc9fec265e3384a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-31T12:42:42.122626Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cfc9fec265e3384a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-31T12:42:42.122821Z","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-01-31T12:42:42.12288Z","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-01-31T12:42:42.122904Z","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-01-31T12:42:42.130575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=(14972778547056425034)"} {"level":"info","ts":"2026-01-31T12:42:42.130699Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9866fe2b0d49ed88","local-member-id":"cfc9fec265e3384a","added-peer-id":"cfc9fec265e3384a","added-peer-peer-urls":["https://162.253.55.206:2380"]} {"level":"info","ts":"2026-01-31T12:42:42.1312Z","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-01-31T12:42:42.131561Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cfc9fec265e3384a","initial-advertise-peer-urls":["https://162.253.55.206:2380"],"listen-peer-urls":["https://162.253.55.206:2380"],"advertise-client-urls":["https://162.253.55.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-31T12:42:42.131628Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-31T12:42:42.132049Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.206:2380"} {"level":"info","ts":"2026-01-31T12:42:42.13208Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.206:2380"} {"level":"info","ts":"2026-01-31T12:42:42.70823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a is starting a new election at term 1"} {"level":"info","ts":"2026-01-31T12:42:42.708328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became pre-candidate at term 1"} {"level":"info","ts":"2026-01-31T12:42:42.708357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a received MsgPreVoteResp from cfc9fec265e3384a at term 1"} {"level":"info","ts":"2026-01-31T12:42:42.708382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became candidate at term 2"} {"level":"info","ts":"2026-01-31T12:42:42.708409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a received MsgVoteResp from cfc9fec265e3384a at term 2"} {"level":"info","ts":"2026-01-31T12:42:42.70843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became leader at term 2"} {"level":"info","ts":"2026-01-31T12:42:42.70848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cfc9fec265e3384a elected leader cfc9fec265e3384a at term 2"} {"level":"info","ts":"2026-01-31T12:42:42.712188Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T12:42:42.712394Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cfc9fec265e3384a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.206:2379]}","request-path":"/0/members/cfc9fec265e3384a/attributes","cluster-id":"9866fe2b0d49ed88","publish-timeout":"7s"} {"level":"info","ts":"2026-01-31T12:42:42.715792Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-31T12:42:42.716108Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-31T12:42:42.716226Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-31T12:42:42.716256Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-31T12:42:42.717443Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9866fe2b0d49ed88","local-member-id":"cfc9fec265e3384a","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T12:42:42.717531Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T12:42:42.717553Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T12:42:42.719565Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.206:2379"} {"level":"info","ts":"2026-01-31T12:42:42.721603Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-31T12:42:56.470063Z","caller":"traceutil/trace.go:171","msg":"trace[1446313567] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:417; }","duration":"412.967967ms","start":"2026-01-31T12:42:56.057078Z","end":"2026-01-31T12:42:56.470046Z","steps":["trace[1446313567] 'read index received' (duration: 412.859544ms)","trace[1446313567] 'applied index is now lower than readState.Index' (duration: 107.573µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T12:42:56.470204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.108061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-01-31T12:42:56.470228Z","caller":"traceutil/trace.go:171","msg":"trace[494624758] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:405; }","duration":"413.179492ms","start":"2026-01-31T12:42:56.057043Z","end":"2026-01-31T12:42:56.470222Z","steps":["trace[494624758] 'agreement among raft nodes before linearized reading' (duration: 413.08519ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:42:56.470251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:42:56.057027Z","time spent":"413.218694ms","remote":"127.0.0.1:49110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6922,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-01-31T12:42:56.470424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:42:55.914986Z","time spent":"555.437649ms","remote":"127.0.0.1:48938","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-01-31T12:43:02.856309Z","caller":"traceutil/trace.go:171","msg":"trace[578631378] linearizableReadLoop","detail":"{readStateIndex:471; appliedIndex:470; }","duration":"292.565764ms","start":"2026-01-31T12:43:02.563731Z","end":"2026-01-31T12:43:02.856297Z","steps":["trace[578631378] 'read index received' (duration: 292.471331ms)","trace[578631378] 'applied index is now lower than readState.Index' (duration: 93.873µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T12:43:02.856519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.780679ms","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-01-31T12:43:02.856557Z","caller":"traceutil/trace.go:171","msg":"trace[1221309719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:455; }","duration":"292.841001ms","start":"2026-01-31T12:43:02.563695Z","end":"2026-01-31T12:43:02.856536Z","steps":["trace[1221309719] 'agreement among raft nodes before linearized reading' (duration: 292.78906ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T12:43:02.856637Z","caller":"traceutil/trace.go:171","msg":"trace[1428964028] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"306.679601ms","start":"2026-01-31T12:43:02.549951Z","end":"2026-01-31T12:43:02.856631Z","steps":["trace[1428964028] 'process raft request' (duration: 306.254189ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:43:02.856921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:43:02.549941Z","time spent":"306.707861ms","remote":"127.0.0.1:49212","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-01-31T12:43:11.417598Z","caller":"traceutil/trace.go:171","msg":"trace[1287733918] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:524; }","duration":"471.243767ms","start":"2026-01-31T12:43:10.946335Z","end":"2026-01-31T12:43:11.417579Z","steps":["trace[1287733918] 'read index received' (duration: 50.568344ms)","trace[1287733918] 'applied index is now lower than readState.Index' (duration: 420.674743ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T12:43:11.417692Z","caller":"traceutil/trace.go:171","msg":"trace[970915650] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"506.278575ms","start":"2026-01-31T12:43:10.911407Z","end":"2026-01-31T12:43:11.417685Z","steps":["trace[970915650] 'process raft request' (duration: 506.050599ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:43:11.417776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:43:10.911396Z","time spent":"506.315536ms","remote":"127.0.0.1:49094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4254,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T12:43:11.417905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.053264ms","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-01-31T12:43:11.417959Z","caller":"traceutil/trace.go:171","msg":"trace[1270036210] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:509; }","duration":"455.152636ms","start":"2026-01-31T12:43:10.962792Z","end":"2026-01-31T12:43:11.417945Z","steps":["trace[1270036210] 'agreement among raft nodes before linearized reading' (duration: 455.012863ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:43:11.417989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:43:10.962754Z","time spent":"455.227468ms","remote":"127.0.0.1:49212","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-01-31T12:43:11.418065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.75104ms","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-01-31T12:43:11.418091Z","caller":"traceutil/trace.go:171","msg":"trace[1340584576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:509; }","duration":"471.799232ms","start":"2026-01-31T12:43:10.946285Z","end":"2026-01-31T12:43:11.418084Z","steps":["trace[1340584576] 'agreement among raft nodes before linearized reading' (duration: 471.7124ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:43:11.418114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:43:10.946226Z","time spent":"471.883735ms","remote":"127.0.0.1:49212","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":"info","ts":"2026-01-31T12:43:13.716446Z","caller":"traceutil/trace.go:171","msg":"trace[401135888] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"125.303345ms","start":"2026-01-31T12:43:13.591127Z","end":"2026-01-31T12:43:13.71643Z","steps":["trace[401135888] 'process raft request' (duration: 125.202472ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T12:43:15.655286Z","caller":"traceutil/trace.go:171","msg":"trace[482041608] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:536; }","duration":"206.123459ms","start":"2026-01-31T12:43:15.449142Z","end":"2026-01-31T12:43:15.655265Z","steps":["trace[482041608] 'read index received' (duration: 205.924314ms)","trace[482041608] 'applied index is now lower than readState.Index' (duration: 198.145µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T12:43:15.655527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.363886ms","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-01-31T12:43:15.655512Z","caller":"traceutil/trace.go:171","msg":"trace[996433709] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"211.830522ms","start":"2026-01-31T12:43:15.443648Z","end":"2026-01-31T12:43:15.655479Z","steps":["trace[996433709] 'process raft request' (duration: 211.442262ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T12:43:15.655561Z","caller":"traceutil/trace.go:171","msg":"trace[615155172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:519; }","duration":"206.433447ms","start":"2026-01-31T12:43:15.449116Z","end":"2026-01-31T12:43:15.65555Z","steps":["trace[615155172] 'agreement among raft nodes before linearized reading' (duration: 206.249312ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:43:19.210104Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056226024444542848,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-31T12:43:19.23971Z","caller":"traceutil/trace.go:171","msg":"trace[55580707] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"701.969014ms","start":"2026-01-31T12:43:18.537715Z","end":"2026-01-31T12:43:19.239684Z","steps":["trace[55580707] 'process raft request' (duration: 701.81521ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:43:19.239922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:43:18.537672Z","time spent":"702.150249ms","remote":"127.0.0.1:49212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T12:43:19.240104Z","caller":"traceutil/trace.go:171","msg":"trace[321751756] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:549; }","duration":"530.320769ms","start":"2026-01-31T12:43:18.709756Z","end":"2026-01-31T12:43:19.240077Z","steps":["trace[321751756] 'read index received' (duration: 530.302018ms)","trace[321751756] 'applied index is now lower than readState.Index' (duration: 6.181µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T12:43:19.240265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.745475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T12:43:19.240305Z","caller":"traceutil/trace.go:171","msg":"trace[1450779538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"386.835878ms","start":"2026-01-31T12:43:18.853451Z","end":"2026-01-31T12:43:19.240287Z","steps":["trace[1450779538] 'agreement among raft nodes before linearized reading' (duration: 386.751415ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:43:19.24034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:43:18.853433Z","time spent":"386.899409ms","remote":"127.0.0.1:48918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-31T12:43:19.24051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.74677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-l2ccz\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-01-31T12:43:19.240552Z","caller":"traceutil/trace.go:171","msg":"trace[1382717154] range","detail":"{range_begin:/registry/pods/kube-system/cilium-l2ccz; range_end:; response_count:1; response_revision:530; }","duration":"530.823902ms","start":"2026-01-31T12:43:18.709716Z","end":"2026-01-31T12:43:19.24054Z","steps":["trace[1382717154] 'agreement among raft nodes before linearized reading' (duration: 530.684389ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:43:19.240581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:43:18.709704Z","time spent":"530.871453ms","remote":"127.0.0.1:49110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16680,"request content":"key:\"/registry/pods/kube-system/cilium-l2ccz\" "} {"level":"warn","ts":"2026-01-31T12:43:19.240628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.228438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-31T12:43:19.240681Z","caller":"traceutil/trace.go:171","msg":"trace[217950067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:530; }","duration":"179.315651ms","start":"2026-01-31T12:43:19.061356Z","end":"2026-01-31T12:43:19.240671Z","steps":["trace[217950067] 'agreement among raft nodes before linearized reading' (duration: 179.227068ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:43:21.281292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.081918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T12:43:21.281367Z","caller":"traceutil/trace.go:171","msg":"trace[88289397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:541; }","duration":"429.200721ms","start":"2026-01-31T12:43:20.852149Z","end":"2026-01-31T12:43:21.28135Z","steps":["trace[88289397] 'range keys from in-memory index tree' (duration: 429.012326ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:43:21.281406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:43:20.85213Z","time spent":"429.267983ms","remote":"127.0.0.1:48918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-31T12:43:21.282765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.617061ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-01-31T12:43:35.482605Z","caller":"traceutil/trace.go:171","msg":"trace[1909893017] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"157.047455ms","start":"2026-01-31T12:43:35.325528Z","end":"2026-01-31T12:43:35.482575Z","steps":["trace[1909893017] 'process raft request' (duration: 156.611553ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:43:36.191884Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056226024444543191,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-31T12:43:36.423875Z","caller":"traceutil/trace.go:171","msg":"trace[1512641948] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"937.327166ms","start":"2026-01-31T12:43:35.486523Z","end":"2026-01-31T12:43:36.42385Z","steps":["trace[1512641948] 'process raft request' (duration: 885.849088ms)","trace[1512641948] 'compare' (duration: 51.383956ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T12:43:36.424232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:43:35.486509Z","time spent":"937.656735ms","remote":"127.0.0.1:49212","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-01-31T12:43:36.964948Z","caller":"traceutil/trace.go:171","msg":"trace[1270588346] linearizableReadLoop","detail":"{readStateIndex:738; appliedIndex:736; }","duration":"1.273484686s","start":"2026-01-31T12:43:35.69144Z","end":"2026-01-31T12:43:36.964925Z","steps":["trace[1270588346] 'read index received' (duration: 680.941461ms)","trace[1270588346] 'applied index is now lower than readState.Index' (duration: 592.542195ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T12:43:36.965145Z","caller":"traceutil/trace.go:171","msg":"trace[1661897137] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"873.458316ms","start":"2026-01-31T12:43:36.091613Z","end":"2026-01-31T12:43:36.965071Z","steps":["trace[1661897137] 'process raft request' (duration: 808.723703ms)","trace[1661897137] 'compare' (duration: 64.035364ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T12:43:36.965233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:43:36.091597Z","time spent":"873.58538ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":815,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-31T12:43:36.965522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.274075502s","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-01-31T12:43:36.965567Z","caller":"traceutil/trace.go:171","msg":"trace[1153689381] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:715; }","duration":"1.274160755s","start":"2026-01-31T12:43:35.691395Z","end":"2026-01-31T12:43:36.965556Z","steps":["trace[1153689381] 'agreement among raft nodes before linearized reading' (duration: 1.274007541s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:43:36.965598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:43:35.691376Z","time spent":"1.274214806s","remote":"127.0.0.1:49212","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-01-31T12:43:36.965767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.198496688s","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-01-31T12:43:36.965793Z","caller":"traceutil/trace.go:171","msg":"trace[1685486561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:715; }","duration":"1.19855238s","start":"2026-01-31T12:43:35.767233Z","end":"2026-01-31T12:43:36.965785Z","steps":["trace[1685486561] 'agreement among raft nodes before linearized reading' (duration: 1.198492288s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:43:36.96581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:43:35.767217Z","time spent":"1.19858973s","remote":"127.0.0.1:49212","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-01-31T12:43:36.965953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02564919s","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-01-31T12:43:36.965977Z","caller":"traceutil/trace.go:171","msg":"trace[2000537090] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:715; }","duration":"1.025713413s","start":"2026-01-31T12:43:35.940258Z","end":"2026-01-31T12:43:36.965971Z","steps":["trace[2000537090] 'agreement among raft nodes before linearized reading' (duration: 1.025660451s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:43:36.965994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:43:35.94024Z","time spent":"1.025750473s","remote":"127.0.0.1:49212","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-01-31T12:43:36.966098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049236842s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.206\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-31T12:43:36.966115Z","caller":"traceutil/trace.go:171","msg":"trace[1979624924] range","detail":"{range_begin:/registry/masterleases/162.253.55.206; range_end:; response_count:1; response_revision:715; }","duration":"1.049277024s","start":"2026-01-31T12:43:35.916834Z","end":"2026-01-31T12:43:36.966111Z","steps":["trace[1979624924] 'agreement among raft nodes before linearized reading' (duration: 1.049229322s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:43:36.966131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:43:35.916821Z","time spent":"1.049306674s","remote":"127.0.0.1:48938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.206\" "} {"level":"warn","ts":"2026-01-31T12:43:36.966213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.115970739s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T12:43:36.966246Z","caller":"traceutil/trace.go:171","msg":"trace[258599758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:715; }","duration":"1.1160155s","start":"2026-01-31T12:43:35.850214Z","end":"2026-01-31T12:43:36.966229Z","steps":["trace[258599758] 'agreement among raft nodes before linearized reading' (duration: 1.115986779s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:43:36.966267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:43:35.8502Z","time spent":"1.116062522s","remote":"127.0.0.1:48918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-31T12:43:36.966415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.194556043s","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-01-31T12:43:36.966448Z","caller":"traceutil/trace.go:171","msg":"trace[1418249446] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:715; }","duration":"1.194608585s","start":"2026-01-31T12:43:35.771834Z","end":"2026-01-31T12:43:36.966442Z","steps":["trace[1418249446] 'agreement among raft nodes before linearized reading' (duration: 1.194547443s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:43:36.966464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:43:35.771824Z","time spent":"1.194636165s","remote":"127.0.0.1:49212","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":"info","ts":"2026-01-31T12:43:41.152429Z","caller":"traceutil/trace.go:171","msg":"trace[1743300211] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"112.749068ms","start":"2026-01-31T12:43:41.039667Z","end":"2026-01-31T12:43:41.152416Z","steps":["trace[1743300211] 'process raft request' (duration: 112.728628ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T12:43:41.152542Z","caller":"traceutil/trace.go:171","msg":"trace[560654107] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"113.222292ms","start":"2026-01-31T12:43:41.039315Z","end":"2026-01-31T12:43:41.152537Z","steps":["trace[560654107] 'process raft request' (duration: 106.953954ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:43:41.440764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.470177ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-01-31T12:43:58.855829Z","caller":"traceutil/trace.go:171","msg":"trace[723132944] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"209.369136ms","start":"2026-01-31T12:43:58.646448Z","end":"2026-01-31T12:43:58.855817Z","steps":["trace[723132944] 'process raft request' (duration: 209.132439ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T12:43:59.3191Z","caller":"traceutil/trace.go:171","msg":"trace[2118570270] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:811; }","duration":"164.163365ms","start":"2026-01-31T12:43:59.154921Z","end":"2026-01-31T12:43:59.319084Z","steps":["trace[2118570270] 'read index received' (duration: 164.047082ms)","trace[2118570270] 'applied index is now lower than readState.Index' (duration: 115.553µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T12:43:59.319179Z","caller":"traceutil/trace.go:171","msg":"trace[56139257] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"192.049602ms","start":"2026-01-31T12:43:59.127124Z","end":"2026-01-31T12:43:59.319174Z","steps":["trace[56139257] 'process raft request' (duration: 191.860287ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:43:59.319341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.400421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-31T12:43:59.319358Z","caller":"traceutil/trace.go:171","msg":"trace[24977009] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:784; }","duration":"164.455873ms","start":"2026-01-31T12:43:59.154896Z","end":"2026-01-31T12:43:59.319351Z","steps":["trace[24977009] 'agreement among raft nodes before linearized reading' (duration: 164.359841ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:43:59.31951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.781253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-31T12:43:59.319553Z","caller":"traceutil/trace.go:171","msg":"trace[1435045968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:784; }","duration":"114.830225ms","start":"2026-01-31T12:43:59.204713Z","end":"2026-01-31T12:43:59.319543Z","steps":["trace[1435045968] 'agreement among raft nodes before linearized reading' (duration: 114.716851ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:43:59.319592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.93628ms","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-01-31T12:43:59.319608Z","caller":"traceutil/trace.go:171","msg":"trace[1868529886] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:784; }","duration":"107.968621ms","start":"2026-01-31T12:43:59.211635Z","end":"2026-01-31T12:43:59.319604Z","steps":["trace[1868529886] 'agreement among raft nodes before linearized reading' (duration: 107.92691ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T12:43:59.522909Z","caller":"traceutil/trace.go:171","msg":"trace[2112782055] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"199.710897ms","start":"2026-01-31T12:43:59.322979Z","end":"2026-01-31T12:43:59.52269Z","steps":["trace[2112782055] 'process raft request' (duration: 199.546912ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T12:43:59.53817Z","caller":"traceutil/trace.go:171","msg":"trace[1416389659] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"213.855266ms","start":"2026-01-31T12:43:59.324305Z","end":"2026-01-31T12:43:59.53816Z","steps":["trace[1416389659] 'process raft request' (duration: 213.619059ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T12:43:59.538125Z","caller":"traceutil/trace.go:171","msg":"trace[1303560507] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"213.753033ms","start":"2026-01-31T12:43:59.324357Z","end":"2026-01-31T12:43:59.53811Z","steps":["trace[1303560507] 'process raft request' (duration: 213.690082ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T12:44:00.06695Z","caller":"traceutil/trace.go:171","msg":"trace[595965870] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"141.612052ms","start":"2026-01-31T12:43:59.925316Z","end":"2026-01-31T12:44:00.066928Z","steps":["trace[595965870] 'process raft request' (duration: 141.379376ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T12:44:01.02446Z","caller":"traceutil/trace.go:171","msg":"trace[1165789851] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"163.330943ms","start":"2026-01-31T12:44:00.861107Z","end":"2026-01-31T12:44:01.024437Z","steps":["trace[1165789851] 'process raft request' (duration: 162.971253ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:01.407619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.932824ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-01-31T12:44:14.268358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.350957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T12:44:14.268438Z","caller":"traceutil/trace.go:171","msg":"trace[222212206] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:874; }","duration":"538.47163ms","start":"2026-01-31T12:44:13.729949Z","end":"2026-01-31T12:44:14.26842Z","steps":["trace[222212206] 'count revisions from in-memory index tree' (duration: 538.297575ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:14.26848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:44:13.729934Z","time spent":"538.533821ms","remote":"127.0.0.1:49076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-01-31T12:44:14.268661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.128079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-31T12:44:14.268657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.252233ms","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-01-31T12:44:14.268729Z","caller":"traceutil/trace.go:171","msg":"trace[1359856365] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:874; }","duration":"669.354945ms","start":"2026-01-31T12:44:13.599356Z","end":"2026-01-31T12:44:14.268711Z","steps":["trace[1359856365] 'range keys from in-memory index tree' (duration: 669.104489ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:14.268772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:44:13.599341Z","time spent":"669.421968ms","remote":"127.0.0.1:49212","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-01-31T12:44:14.268991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.902088ms","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-01-31T12:44:14.26902Z","caller":"traceutil/trace.go:171","msg":"trace[508984925] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:874; }","duration":"656.942929ms","start":"2026-01-31T12:44:13.612068Z","end":"2026-01-31T12:44:14.269011Z","steps":["trace[508984925] 'range keys from in-memory index tree' (duration: 656.814746ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:14.269025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.785613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-01-31T12:44:14.269048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:44:13.612061Z","time spent":"656.98009ms","remote":"127.0.0.1:49212","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":"info","ts":"2026-01-31T12:44:14.269063Z","caller":"traceutil/trace.go:171","msg":"trace[1412068470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:874; }","duration":"652.832964ms","start":"2026-01-31T12:44:13.61622Z","end":"2026-01-31T12:44:14.269053Z","steps":["trace[1412068470] 'range keys from in-memory index tree' (duration: 652.691571ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:14.269094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:44:13.616215Z","time spent":"652.870706ms","remote":"127.0.0.1:49212","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":"info","ts":"2026-01-31T12:44:14.268689Z","caller":"traceutil/trace.go:171","msg":"trace[1660007127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:874; }","duration":"419.181791ms","start":"2026-01-31T12:44:13.849498Z","end":"2026-01-31T12:44:14.268679Z","steps":["trace[1660007127] 'range keys from in-memory index tree' (duration: 419.064347ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:14.269174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:44:13.849464Z","time spent":"419.703994ms","remote":"127.0.0.1:48918","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-01-31T12:44:14.529591Z","caller":"traceutil/trace.go:171","msg":"trace[10206811] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"250.421323ms","start":"2026-01-31T12:44:14.279148Z","end":"2026-01-31T12:44:14.529569Z","steps":["trace[10206811] 'process raft request' (duration: 250.2889ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T12:44:14.871246Z","caller":"traceutil/trace.go:171","msg":"trace[110467939] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"591.953236ms","start":"2026-01-31T12:44:14.27928Z","end":"2026-01-31T12:44:14.871233Z","steps":["trace[110467939] 'process raft request' (duration: 591.662738ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:14.871333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:44:14.279274Z","time spent":"592.010427ms","remote":"127.0.0.1:49212","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-01-31T12:44:14.871412Z","caller":"traceutil/trace.go:171","msg":"trace[1321133668] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"590.129009ms","start":"2026-01-31T12:44:14.281279Z","end":"2026-01-31T12:44:14.871408Z","steps":["trace[1321133668] 'process raft request' (duration: 589.740169ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:14.871436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:44:14.281272Z","time spent":"590.150669ms","remote":"127.0.0.1:49212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T12:44:15.693813Z","caller":"traceutil/trace.go:171","msg":"trace[1095856479] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"195.986114ms","start":"2026-01-31T12:44:15.49781Z","end":"2026-01-31T12:44:15.693796Z","steps":["trace[1095856479] 'process raft request' (duration: 195.893032ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T12:44:16.729573Z","caller":"traceutil/trace.go:171","msg":"trace[419421076] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"106.965556ms","start":"2026-01-31T12:44:16.622592Z","end":"2026-01-31T12:44:16.729557Z","steps":["trace[419421076] 'process raft request' (duration: 106.870493ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T12:44:23.162671Z","caller":"traceutil/trace.go:171","msg":"trace[439199464] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:932; }","duration":"313.922779ms","start":"2026-01-31T12:44:22.848735Z","end":"2026-01-31T12:44:23.162658Z","steps":["trace[439199464] 'read index received' (duration: 313.839187ms)","trace[439199464] 'applied index is now lower than readState.Index' (duration: 83.002µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T12:44:23.162744Z","caller":"traceutil/trace.go:171","msg":"trace[1504798386] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"581.325133ms","start":"2026-01-31T12:44:22.581414Z","end":"2026-01-31T12:44:23.162739Z","steps":["trace[1504798386] 'process raft request' (duration: 581.159419ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:23.162801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:44:22.581405Z","time spent":"581.351724ms","remote":"127.0.0.1:49212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T12:44:23.162913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.399226ms","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-01-31T12:44:23.162963Z","caller":"traceutil/trace.go:171","msg":"trace[1658977688] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:898; }","duration":"240.479818ms","start":"2026-01-31T12:44:22.922469Z","end":"2026-01-31T12:44:23.162949Z","steps":["trace[1658977688] 'agreement among raft nodes before linearized reading' (duration: 240.350634ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:23.163046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.310199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T12:44:23.163066Z","caller":"traceutil/trace.go:171","msg":"trace[1022314550] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:898; }","duration":"314.363071ms","start":"2026-01-31T12:44:22.848697Z","end":"2026-01-31T12:44:23.16306Z","steps":["trace[1022314550] 'agreement among raft nodes before linearized reading' (duration: 314.33641ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:23.163077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:44:22.848685Z","time spent":"314.389151ms","remote":"127.0.0.1:48918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-31T12:44:23.163124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.076266ms","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-01-31T12:44:23.163147Z","caller":"traceutil/trace.go:171","msg":"trace[780004536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:898; }","duration":"236.098777ms","start":"2026-01-31T12:44:22.927042Z","end":"2026-01-31T12:44:23.163141Z","steps":["trace[780004536] 'agreement among raft nodes before linearized reading' (duration: 236.042065ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T12:44:24.077491Z","caller":"traceutil/trace.go:171","msg":"trace[286978804] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:936; }","duration":"227.837716ms","start":"2026-01-31T12:44:23.849639Z","end":"2026-01-31T12:44:24.077477Z","steps":["trace[286978804] 'read index received' (duration: 227.722513ms)","trace[286978804] 'applied index is now lower than readState.Index' (duration: 114.593µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T12:44:24.077708Z","caller":"traceutil/trace.go:171","msg":"trace[857396052] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"358.968427ms","start":"2026-01-31T12:44:23.718734Z","end":"2026-01-31T12:44:24.077702Z","steps":["trace[857396052] 'process raft request' (duration: 358.668629ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:24.077769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:44:23.718724Z","time spent":"358.999198ms","remote":"127.0.0.1:49212","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-01-31T12:44:24.07778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.06651ms","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":"info","ts":"2026-01-31T12:44:24.07783Z","caller":"traceutil/trace.go:171","msg":"trace[708376114] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:902; }","duration":"173.159153ms","start":"2026-01-31T12:44:23.904659Z","end":"2026-01-31T12:44:24.077819Z","steps":["trace[708376114] 'agreement among raft nodes before linearized reading' (duration: 173.03447ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:24.077918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.278037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T12:44:24.077937Z","caller":"traceutil/trace.go:171","msg":"trace[261282904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:902; }","duration":"228.318338ms","start":"2026-01-31T12:44:23.849613Z","end":"2026-01-31T12:44:24.077932Z","steps":["trace[261282904] 'agreement among raft nodes before linearized reading' (duration: 228.287597ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T12:44:27.280455Z","caller":"traceutil/trace.go:171","msg":"trace[56138] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:944; }","duration":"106.052653ms","start":"2026-01-31T12:44:27.174387Z","end":"2026-01-31T12:44:27.28044Z","steps":["trace[56138] 'read index received' (duration: 105.914559ms)","trace[56138] 'applied index is now lower than readState.Index' (duration: 137.564µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T12:44:27.280673Z","caller":"traceutil/trace.go:171","msg":"trace[1686847746] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"357.776167ms","start":"2026-01-31T12:44:26.922891Z","end":"2026-01-31T12:44:27.280667Z","steps":["trace[1686847746] 'process raft request' (duration: 357.452618ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:27.280734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:44:26.922872Z","time spent":"357.815138ms","remote":"127.0.0.1:49212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T12:44:27.280879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.500384ms","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-01-31T12:44:27.280895Z","caller":"traceutil/trace.go:171","msg":"trace[1437184710] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:909; }","duration":"106.533894ms","start":"2026-01-31T12:44:27.174356Z","end":"2026-01-31T12:44:27.28089Z","steps":["trace[1437184710] 'agreement among raft nodes before linearized reading' (duration: 106.470573ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:27.685749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.112951ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T12:44:27.685824Z","caller":"traceutil/trace.go:171","msg":"trace[1718406199] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"401.05889ms","start":"2026-01-31T12:44:27.284752Z","end":"2026-01-31T12:44:27.685811Z","steps":["trace[1718406199] 'process raft request' (duration: 94.833946ms)","trace[1718406199] 'compare' (duration: 305.867304ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T12:44:27.685856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:44:27.284734Z","time spent":"401.107621ms","remote":"127.0.0.1:49212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T12:44:27.742382Z","caller":"traceutil/trace.go:171","msg":"trace[640494764] linearizableReadLoop","detail":"{readStateIndex:949; appliedIndex:946; }","duration":"105.220151ms","start":"2026-01-31T12:44:27.637147Z","end":"2026-01-31T12:44:27.742367Z","steps":["trace[640494764] 'read index received' (duration: 105.038047ms)","trace[640494764] 'applied index is now lower than readState.Index' (duration: 181.564µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T12:44:27.742474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.317053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T12:44:27.742505Z","caller":"traceutil/trace.go:171","msg":"trace[1678301557] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:913; }","duration":"105.398296ms","start":"2026-01-31T12:44:27.637101Z","end":"2026-01-31T12:44:27.742499Z","steps":["trace[1678301557] 'agreement among raft nodes before linearized reading' (duration: 105.322974ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T12:44:27.7427Z","caller":"traceutil/trace.go:171","msg":"trace[1010069796] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"457.793155ms","start":"2026-01-31T12:44:27.284901Z","end":"2026-01-31T12:44:27.742694Z","steps":["trace[1010069796] 'process raft request' (duration: 457.332493ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:27.742751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:44:27.284897Z","time spent":"457.821255ms","remote":"127.0.0.1:49212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T12:44:27.742821Z","caller":"traceutil/trace.go:171","msg":"trace[1416604165] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"457.877787ms","start":"2026-01-31T12:44:27.284939Z","end":"2026-01-31T12:44:27.742817Z","steps":["trace[1416604165] 'process raft request' (duration: 457.368184ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:27.742843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:44:27.284936Z","time spent":"457.894777ms","remote":"127.0.0.1:49212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T12:44:27.742897Z","caller":"traceutil/trace.go:171","msg":"trace[245007798] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"456.978574ms","start":"2026-01-31T12:44:27.285914Z","end":"2026-01-31T12:44:27.742893Z","steps":["trace[245007798] 'process raft request' (duration: 456.43797ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:27.742917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:44:27.285907Z","time spent":"456.998695ms","remote":"127.0.0.1:49212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T12:44:28.382057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.864683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-01-31T12:44:28.382073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.074711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T12:44:28.382144Z","caller":"traceutil/trace.go:171","msg":"trace[301092739] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:913; }","duration":"529.187863ms","start":"2026-01-31T12:44:27.852939Z","end":"2026-01-31T12:44:28.382126Z","steps":["trace[301092739] 'range keys from in-memory index tree' (duration: 528.962918ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T12:44:28.382104Z","caller":"traceutil/trace.go:171","msg":"trace[2059735425] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:913; }","duration":"283.956305ms","start":"2026-01-31T12:44:28.098136Z","end":"2026-01-31T12:44:28.382093Z","steps":["trace[2059735425] 'range keys from in-memory index tree' (duration: 283.706978ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:28.382196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:44:27.85292Z","time spent":"529.265906ms","remote":"127.0.0.1:48918","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-01-31T12:44:29.964882Z","caller":"traceutil/trace.go:171","msg":"trace[1154467029] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:950; }","duration":"218.96421ms","start":"2026-01-31T12:44:29.745903Z","end":"2026-01-31T12:44:29.964867Z","steps":["trace[1154467029] 'read index received' (duration: 218.888718ms)","trace[1154467029] 'applied index is now lower than readState.Index' (duration: 74.722µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T12:44:29.964961Z","caller":"traceutil/trace.go:171","msg":"trace[2102779377] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"250.490933ms","start":"2026-01-31T12:44:29.714465Z","end":"2026-01-31T12:44:29.964956Z","steps":["trace[2102779377] 'process raft request' (duration: 250.304378ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:29.965182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.272407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-31T12:44:29.965199Z","caller":"traceutil/trace.go:171","msg":"trace[231698703] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:915; }","duration":"219.317118ms","start":"2026-01-31T12:44:29.745877Z","end":"2026-01-31T12:44:29.965194Z","steps":["trace[231698703] 'agreement among raft nodes before linearized reading' (duration: 219.236766ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:29.965363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.805374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T12:44:29.965377Z","caller":"traceutil/trace.go:171","msg":"trace[761629171] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:915; }","duration":"112.843426ms","start":"2026-01-31T12:44:29.852529Z","end":"2026-01-31T12:44:29.965373Z","steps":["trace[761629171] 'agreement among raft nodes before linearized reading' (duration: 112.821165ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:29.965446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.449736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-01-31T12:44:29.965522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.066936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-31T12:44:29.965539Z","caller":"traceutil/trace.go:171","msg":"trace[1209522453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:915; }","duration":"218.110968ms","start":"2026-01-31T12:44:29.747424Z","end":"2026-01-31T12:44:29.965535Z","steps":["trace[1209522453] 'agreement among raft nodes before linearized reading' (duration: 218.066287ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T12:44:29.965529Z","caller":"traceutil/trace.go:171","msg":"trace[1099599938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:915; }","duration":"218.569899ms","start":"2026-01-31T12:44:29.746942Z","end":"2026-01-31T12:44:29.965512Z","steps":["trace[1099599938] 'agreement among raft nodes before linearized reading' (duration: 218.336673ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T12:44:32.809289Z","caller":"traceutil/trace.go:171","msg":"trace[537458234] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"361.055481ms","start":"2026-01-31T12:44:32.448213Z","end":"2026-01-31T12:44:32.809269Z","steps":["trace[537458234] 'process raft request' (duration: 360.938558ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:32.809405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:44:32.448203Z","time spent":"361.142173ms","remote":"127.0.0.1:49212","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-01-31T12:44:33.489737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.959105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T12:44:33.489809Z","caller":"traceutil/trace.go:171","msg":"trace[463904287] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:945; }","duration":"636.042097ms","start":"2026-01-31T12:44:32.853752Z","end":"2026-01-31T12:44:33.489794Z","steps":["trace[463904287] 'range keys from in-memory index tree' (duration: 635.909734ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:33.48985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:44:32.853465Z","time spent":"636.379245ms","remote":"127.0.0.1:48918","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-01-31T12:44:34.426845Z","caller":"traceutil/trace.go:171","msg":"trace[121067541] linearizableReadLoop","detail":"{readStateIndex:983; appliedIndex:982; }","duration":"441.999962ms","start":"2026-01-31T12:44:33.984823Z","end":"2026-01-31T12:44:34.426823Z","steps":["trace[121067541] 'read index received' (duration: 441.791776ms)","trace[121067541] 'applied index is now lower than readState.Index' (duration: 207.336µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T12:44:34.427209Z","caller":"traceutil/trace.go:171","msg":"trace[97017287] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"444.029273ms","start":"2026-01-31T12:44:33.983168Z","end":"2026-01-31T12:44:34.427198Z","steps":["trace[97017287] 'process raft request' (duration: 443.50703ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:34.427309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:44:33.983147Z","time spent":"444.090875ms","remote":"127.0.0.1:49212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T12:44:34.427687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.330949ms","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-01-31T12:44:34.427928Z","caller":"traceutil/trace.go:171","msg":"trace[47619723] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:946; }","duration":"429.487623ms","start":"2026-01-31T12:44:33.998261Z","end":"2026-01-31T12:44:34.427749Z","steps":["trace[47619723] 'agreement among raft nodes before linearized reading' (duration: 429.182756ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:34.427995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:44:33.998246Z","time spent":"429.739919ms","remote":"127.0.0.1:49212","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-01-31T12:44:34.42811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.287464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T12:44:34.428128Z","caller":"traceutil/trace.go:171","msg":"trace[2033373943] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:946; }","duration":"443.307136ms","start":"2026-01-31T12:44:33.984815Z","end":"2026-01-31T12:44:34.428122Z","steps":["trace[2033373943] 'agreement among raft nodes before linearized reading' (duration: 443.258575ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:34.428146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:44:33.984796Z","time spent":"443.344036ms","remote":"127.0.0.1:49088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-01-31T12:44:34.428298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.79227ms","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-01-31T12:44:34.428314Z","caller":"traceutil/trace.go:171","msg":"trace[2115635247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:946; }","duration":"416.832781ms","start":"2026-01-31T12:44:34.011476Z","end":"2026-01-31T12:44:34.428309Z","steps":["trace[2115635247] 'agreement among raft nodes before linearized reading' (duration: 416.769709ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:34.42833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:44:34.011469Z","time spent":"416.857392ms","remote":"127.0.0.1:49212","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-01-31T12:44:34.428402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.668479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-31T12:44:34.428416Z","caller":"traceutil/trace.go:171","msg":"trace[791784593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:946; }","duration":"422.70924ms","start":"2026-01-31T12:44:34.005702Z","end":"2026-01-31T12:44:34.428411Z","steps":["trace[791784593] 'agreement among raft nodes before linearized reading' (duration: 422.665169ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:34.428432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:44:34.005688Z","time spent":"422.739332ms","remote":"127.0.0.1:49212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-31T12:44:34.709629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.527464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T12:44:34.709772Z","caller":"traceutil/trace.go:171","msg":"trace[1458069290] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"272.815102ms","start":"2026-01-31T12:44:34.436945Z","end":"2026-01-31T12:44:34.70976Z","steps":["trace[1458069290] 'process raft request' (duration: 272.794472ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T12:44:34.709959Z","caller":"traceutil/trace.go:171","msg":"trace[461140866] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"274.312361ms","start":"2026-01-31T12:44:34.43563Z","end":"2026-01-31T12:44:34.709943Z","steps":["trace[461140866] 'process raft request' (duration: 65.418417ms)","trace[461140866] 'compare' (duration: 208.461103ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T12:44:34.710027Z","caller":"traceutil/trace.go:171","msg":"trace[61035069] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"273.12682ms","start":"2026-01-31T12:44:34.436894Z","end":"2026-01-31T12:44:34.710021Z","steps":["trace[61035069] 'process raft request' (duration: 272.822022ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:35.004518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.815755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T12:44:35.004571Z","caller":"traceutil/trace.go:171","msg":"trace[5824381] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:949; }","duration":"154.907578ms","start":"2026-01-31T12:44:34.849651Z","end":"2026-01-31T12:44:35.004559Z","steps":["trace[5824381] 'range keys from in-memory index tree' (duration: 154.767244ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:35.004633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.454374ms","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-01-31T12:44:35.004697Z","caller":"traceutil/trace.go:171","msg":"trace[153402680] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:949; }","duration":"192.559057ms","start":"2026-01-31T12:44:34.812124Z","end":"2026-01-31T12:44:35.004684Z","steps":["trace[153402680] 'range keys from in-memory index tree' (duration: 192.310351ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:42.730485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.364922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T12:44:42.73055Z","caller":"traceutil/trace.go:171","msg":"trace[1869882793] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:991; }","duration":"161.481115ms","start":"2026-01-31T12:44:42.569057Z","end":"2026-01-31T12:44:42.730538Z","steps":["trace[1869882793] 'count revisions from in-memory index tree' (duration: 161.248059ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:48.397922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T12:44:48.398002Z","caller":"traceutil/trace.go:171","msg":"trace[1439277507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1031; }","duration":"538.949413ms","start":"2026-01-31T12:44:47.859033Z","end":"2026-01-31T12:44:48.397982Z","steps":["trace[1439277507] 'range keys from in-memory index tree' (duration: 538.711577ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:48.398044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:44:47.859008Z","time spent":"539.027096ms","remote":"127.0.0.1:48902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-31T12:44:48.398596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.63164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-01-31T12:44:48.398644Z","caller":"traceutil/trace.go:171","msg":"trace[2086448979] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1031; }","duration":"225.734372ms","start":"2026-01-31T12:44:48.172896Z","end":"2026-01-31T12:44:48.39863Z","steps":["trace[2086448979] 'range keys from in-memory index tree' (duration: 225.470896ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:48.981788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.982875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T12:44:48.983135Z","caller":"traceutil/trace.go:171","msg":"trace[934903419] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1072; }","duration":"395.076177ms","start":"2026-01-31T12:44:48.586879Z","end":"2026-01-31T12:44:48.981955Z","steps":["trace[934903419] 'read index received' (duration: 41.515528ms)","trace[934903419] 'applied index is now lower than readState.Index' (duration: 353.559109ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T12:44:48.984507Z","caller":"traceutil/trace.go:171","msg":"trace[620820032] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"493.29649ms","start":"2026-01-31T12:44:48.491197Z","end":"2026-01-31T12:44:48.984494Z","steps":["trace[620820032] 'process raft request' (duration: 137.231127ms)","trace[620820032] 'compare' (duration: 352.874901ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T12:44:48.984587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:44:48.491185Z","time spent":"493.376001ms","remote":"127.0.0.1:49212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T12:44:48.984864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.991062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-01-31T12:44:48.984903Z","caller":"traceutil/trace.go:171","msg":"trace[856080060] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1033; }","duration":"398.076224ms","start":"2026-01-31T12:44:48.586815Z","end":"2026-01-31T12:44:48.984891Z","steps":["trace[856080060] 'agreement among raft nodes before linearized reading' (duration: 397.967521ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:48.984935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T12:44:48.586785Z","time spent":"398.143526ms","remote":"127.0.0.1:49212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":534,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-01-31T12:44:48.985115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.674639ms","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-01-31T12:44:48.985147Z","caller":"traceutil/trace.go:171","msg":"trace[607107297] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1033; }","duration":"161.70776ms","start":"2026-01-31T12:44:48.823432Z","end":"2026-01-31T12:44:48.98514Z","steps":["trace[607107297] 'agreement among raft nodes before linearized reading' (duration: 161.639588ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T12:44:48.986195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.956702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T12:44:48.986302Z","caller":"traceutil/trace.go:171","msg":"trace[1544360932] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1033; }","duration":"132.143687ms","start":"2026-01-31T12:44:48.854093Z","end":"2026-01-31T12:44:48.986237Z","steps":["trace[1544360932] 'agreement among raft nodes before linearized reading' (duration: 131.957352ms)"],"step_count":1}