{"level":"warn","ts":"2026-09-30T02:20:46.877515Z","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-30T02:20:46.877616Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.239: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.239:2380","--initial-cluster=instance=https://162.253.55.239:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.239:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.239: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-30T02:20:46.8777Z","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-30T02:20:46.877718Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.239:2380"]} {"level":"info","ts":"2026-09-30T02:20:46.877741Z","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-30T02:20:46.878431Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.239:2379"]} {"level":"info","ts":"2026-09-30T02:20:46.87852Z","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.239:2380"],"listen-peer-urls":["https://162.253.55.239:2380"],"advertise-client-urls":["https://162.253.55.239:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.239:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.239: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-30T02:20:46.883407Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.56278ms"} {"level":"info","ts":"2026-09-30T02:20:46.893898Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"548b318b3528a14c","cluster-id":"8bf5e58b9331730a"} {"level":"info","ts":"2026-09-30T02:20:46.893953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c switched to configuration voters=()"} {"level":"info","ts":"2026-09-30T02:20:46.893973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c became follower at term 0"} {"level":"info","ts":"2026-09-30T02:20:46.893983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 548b318b3528a14c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-30T02:20:46.89399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c became follower at term 1"} {"level":"info","ts":"2026-09-30T02:20:46.894022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c switched to configuration voters=(6092017394910798156)"} {"level":"warn","ts":"2026-09-30T02:20:46.902605Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-30T02:20:46.903997Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-30T02:20:46.905201Z","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-30T02:20:46.907131Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"548b318b3528a14c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-30T02:20:46.90734Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"548b318b3528a14c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-30T02:20:46.907421Z","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-30T02:20:46.907475Z","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-30T02:20:46.907484Z","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-30T02:20:46.910137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c switched to configuration voters=(6092017394910798156)"} {"level":"info","ts":"2026-09-30T02:20:46.910385Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8bf5e58b9331730a","local-member-id":"548b318b3528a14c","added-peer-id":"548b318b3528a14c","added-peer-peer-urls":["https://162.253.55.239:2380"]} {"level":"info","ts":"2026-09-30T02:20:46.913082Z","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-30T02:20:46.913231Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.239:2380"} {"level":"info","ts":"2026-09-30T02:20:46.913304Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.239:2380"} {"level":"info","ts":"2026-09-30T02:20:46.913845Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"548b318b3528a14c","initial-advertise-peer-urls":["https://162.253.55.239:2380"],"listen-peer-urls":["https://162.253.55.239:2380"],"advertise-client-urls":["https://162.253.55.239:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.239:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-30T02:20:46.913868Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-30T02:20:47.894683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c is starting a new election at term 1"} {"level":"info","ts":"2026-09-30T02:20:47.894739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c became pre-candidate at term 1"} {"level":"info","ts":"2026-09-30T02:20:47.89477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c received MsgPreVoteResp from 548b318b3528a14c at term 1"} {"level":"info","ts":"2026-09-30T02:20:47.894782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c became candidate at term 2"} {"level":"info","ts":"2026-09-30T02:20:47.894787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c received MsgVoteResp from 548b318b3528a14c at term 2"} {"level":"info","ts":"2026-09-30T02:20:47.894796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c became leader at term 2"} {"level":"info","ts":"2026-09-30T02:20:47.894803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 548b318b3528a14c elected leader 548b318b3528a14c at term 2"} {"level":"info","ts":"2026-09-30T02:20:47.895836Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"548b318b3528a14c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.239:2379]}","request-path":"/0/members/548b318b3528a14c/attributes","cluster-id":"8bf5e58b9331730a","publish-timeout":"7s"} {"level":"info","ts":"2026-09-30T02:20:47.895868Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-30T02:20:47.896016Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-30T02:20:47.896244Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-30T02:20:47.896447Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-30T02:20:47.896574Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-30T02:20:47.897268Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8bf5e58b9331730a","local-member-id":"548b318b3528a14c","cluster-version":"3.5"} {"level":"info","ts":"2026-09-30T02:20:47.897444Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-30T02:20:47.897511Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-30T02:20:47.898135Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.239:2379"} {"level":"info","ts":"2026-09-30T02:20:47.900474Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-30T02:20:51.564463Z","caller":"traceutil/trace.go:171","msg":"trace[1400820871] transaction","detail":"{read_only:false; response_revision:171; number_of_response:1; }","duration":"123.787552ms","start":"2026-09-30T02:20:51.440633Z","end":"2026-09-30T02:20:51.564421Z","steps":["trace[1400820871] 'process raft request' (duration: 66.670084ms)","trace[1400820871] 'compare' (duration: 56.745828ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T02:20:52.031061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.652464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-30T02:20:52.031199Z","caller":"traceutil/trace.go:171","msg":"trace[1244611702] linearizableReadLoop","detail":"{readStateIndex:177; appliedIndex:176; }","duration":"243.886593ms","start":"2026-09-30T02:20:51.787294Z","end":"2026-09-30T02:20:52.03118Z","steps":["trace[1244611702] 'read index received' (duration: 87.431189ms)","trace[1244611702] 'applied index is now lower than readState.Index' (duration: 156.454284ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T02:20:52.031289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.000047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-30T02:20:52.031329Z","caller":"traceutil/trace.go:171","msg":"trace[324496216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:173; }","duration":"244.086198ms","start":"2026-09-30T02:20:51.787218Z","end":"2026-09-30T02:20:52.031304Z","steps":["trace[324496216] 'agreement among raft nodes before linearized reading' (duration: 244.006706ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:20:52.032227Z","caller":"traceutil/trace.go:171","msg":"trace[462339870] transaction","detail":"{read_only:false; response_revision:173; number_of_response:1; }","duration":"404.679023ms","start":"2026-09-30T02:20:51.626825Z","end":"2026-09-30T02:20:52.031504Z","steps":["trace[462339870] 'process raft request' (duration: 248.063445ms)","trace[462339870] 'compare' (duration: 155.505021ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T02:20:52.032532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:20:51.626802Z","time spent":"405.620127ms","remote":"127.0.0.1:44626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-30T02:20:52.474367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.745559ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-09-30T02:20:52.474533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:20:52.086749Z","time spent":"387.778686ms","remote":"127.0.0.1:44346","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-09-30T02:20:52.476001Z","caller":"traceutil/trace.go:171","msg":"trace[1453782436] linearizableReadLoop","detail":"{readStateIndex:180; appliedIndex:178; }","duration":"350.013129ms","start":"2026-09-30T02:20:52.125952Z","end":"2026-09-30T02:20:52.475966Z","steps":["trace[1453782436] 'read index received' (duration: 131.695572ms)","trace[1453782436] 'applied index is now lower than readState.Index' (duration: 218.316447ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T02:20:52.476362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.394538ms","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-30T02:20:52.476655Z","caller":"traceutil/trace.go:171","msg":"trace[1415335197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:175; }","duration":"350.747788ms","start":"2026-09-30T02:20:52.125887Z","end":"2026-09-30T02:20:52.476635Z","steps":["trace[1415335197] 'agreement among raft nodes before linearized reading' (duration: 350.353448ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:20:52.477015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:20:52.125858Z","time spent":"351.113046ms","remote":"127.0.0.1:44546","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-09-30T02:20:52.476786Z","caller":"traceutil/trace.go:171","msg":"trace[1578600587] transaction","detail":"{read_only:false; response_revision:175; number_of_response:1; }","duration":"351.436194ms","start":"2026-09-30T02:20:52.125324Z","end":"2026-09-30T02:20:52.476761Z","steps":["trace[1578600587] 'process raft request' (duration: 350.444939ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:20:52.477507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:20:52.125293Z","time spent":"352.156112ms","remote":"127.0.0.1:44626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":748,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-30T02:21:31.033931Z","caller":"traceutil/trace.go:171","msg":"trace[934974868] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"329.822946ms","start":"2026-09-30T02:21:30.704087Z","end":"2026-09-30T02:21:31.03391Z","steps":["trace[934974868] 'process raft request' (duration: 329.687784ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:21:31.034192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:21:30.704063Z","time spent":"329.958739ms","remote":"127.0.0.1:44312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-30T02:21:31.112267Z","caller":"traceutil/trace.go:171","msg":"trace[291484692] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"399.364583ms","start":"2026-09-30T02:21:30.712868Z","end":"2026-09-30T02:21:31.112232Z","steps":["trace[291484692] 'process raft request' (duration: 399.18206ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:21:31.112375Z","caller":"traceutil/trace.go:171","msg":"trace[1286195678] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"389.08844ms","start":"2026-09-30T02:21:30.723278Z","end":"2026-09-30T02:21:31.112367Z","steps":["trace[1286195678] 'process raft request' (duration: 388.937988ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:21:31.11255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:21:30.712849Z","time spent":"399.623547ms","remote":"127.0.0.1:44312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-30T02:21:31.112658Z","caller":"traceutil/trace.go:171","msg":"trace[1525134706] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"369.976729ms","start":"2026-09-30T02:21:30.742662Z","end":"2026-09-30T02:21:31.112639Z","steps":["trace[1525134706] 'process raft request' (duration: 369.756745ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:21:31.112735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:21:30.742633Z","time spent":"370.060261ms","remote":"127.0.0.1:44312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-30T02:21:31.112837Z","caller":"traceutil/trace.go:171","msg":"trace[1825961526] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"369.377289ms","start":"2026-09-30T02:21:30.74338Z","end":"2026-09-30T02:21:31.112757Z","steps":["trace[1825961526] 'process raft request' (duration: 369.081864ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:21:31.112859Z","caller":"traceutil/trace.go:171","msg":"trace[1538049460] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"379.626431ms","start":"2026-09-30T02:21:30.733223Z","end":"2026-09-30T02:21:31.11285Z","steps":["trace[1538049460] 'process raft request' (duration: 379.157424ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:21:31.112929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:21:30.7332Z","time spent":"379.706542ms","remote":"127.0.0.1:44312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-30T02:21:31.113011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:21:30.743371Z","time spent":"369.560272ms","remote":"127.0.0.1:44312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-30T02:21:31.113104Z","caller":"traceutil/trace.go:171","msg":"trace[1577926636] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:558; }","duration":"383.629498ms","start":"2026-09-30T02:21:30.72944Z","end":"2026-09-30T02:21:31.11307Z","steps":["trace[1577926636] 'read index received' (duration: 304.497571ms)","trace[1577926636] 'applied index is now lower than readState.Index' (duration: 79.130957ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:21:31.113093Z","caller":"traceutil/trace.go:171","msg":"trace[111920375] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"388.334938ms","start":"2026-09-30T02:21:30.724244Z","end":"2026-09-30T02:21:31.112579Z","steps":["trace[111920375] 'process raft request' (duration: 388.042903ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:21:31.113149Z","caller":"traceutil/trace.go:171","msg":"trace[449587933] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"347.599673ms","start":"2026-09-30T02:21:30.765537Z","end":"2026-09-30T02:21:31.113136Z","steps":["trace[449587933] 'process raft request' (duration: 346.973823ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:21:31.113232Z","caller":"traceutil/trace.go:171","msg":"trace[877007428] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"221.099018ms","start":"2026-09-30T02:21:30.892126Z","end":"2026-09-30T02:21:31.113225Z","steps":["trace[877007428] 'process raft request' (duration: 220.748902ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:21:31.113302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:21:30.765504Z","time spent":"347.735356ms","remote":"127.0.0.1:44312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-30T02:21:31.113207Z","caller":"traceutil/trace.go:171","msg":"trace[1740852824] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"287.91783ms","start":"2026-09-30T02:21:30.825279Z","end":"2026-09-30T02:21:31.113197Z","steps":["trace[1740852824] 'process raft request' (duration: 287.437842ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:21:31.113439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.978174ms","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-30T02:21:31.113478Z","caller":"traceutil/trace.go:171","msg":"trace[694288320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:554; }","duration":"384.052256ms","start":"2026-09-30T02:21:30.729417Z","end":"2026-09-30T02:21:31.113469Z","steps":["trace[694288320] 'agreement among raft nodes before linearized reading' (duration: 383.963464ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:21:31.113505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:21:30.729407Z","time spent":"384.090936ms","remote":"127.0.0.1:44546","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-09-30T02:21:31.113304Z","caller":"traceutil/trace.go:171","msg":"trace[283726644] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"327.515216ms","start":"2026-09-30T02:21:30.785739Z","end":"2026-09-30T02:21:31.113254Z","steps":["trace[283726644] 'process raft request' (duration: 326.864635ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:21:31.113374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:21:30.724227Z","time spent":"388.972598ms","remote":"127.0.0.1:44312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-30T02:21:31.113689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:21:30.785706Z","time spent":"327.938833ms","remote":"127.0.0.1:44312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-30T02:21:31.112315Z","caller":"traceutil/trace.go:171","msg":"trace[1053266006] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"394.000503ms","start":"2026-09-30T02:21:30.718292Z","end":"2026-09-30T02:21:31.112292Z","steps":["trace[1053266006] 'process raft request' (duration: 393.877031ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:21:31.113872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:21:30.718276Z","time spent":"395.502548ms","remote":"127.0.0.1:44312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-30T02:21:31.114123Z","caller":"traceutil/trace.go:171","msg":"trace[2013313626] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"384.133967ms","start":"2026-09-30T02:21:30.729392Z","end":"2026-09-30T02:21:31.113526Z","steps":["trace[2013313626] 'process raft request' (duration: 382.945737ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:21:31.114124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:21:30.723259Z","time spent":"389.179082ms","remote":"127.0.0.1:44312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-30T02:21:31.114226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:21:30.729362Z","time spent":"384.798458ms","remote":"127.0.0.1:44312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-30T02:21:31.118868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.094563ms","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-30T02:21:31.118923Z","caller":"traceutil/trace.go:171","msg":"trace[1392197267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:556; }","duration":"260.182034ms","start":"2026-09-30T02:21:30.858729Z","end":"2026-09-30T02:21:31.118911Z","steps":["trace[1392197267] 'agreement among raft nodes before linearized reading' (duration: 260.079752ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:21:31.119203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.036497ms","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-30T02:21:31.119253Z","caller":"traceutil/trace.go:171","msg":"trace[1654946923] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:556; }","duration":"180.112499ms","start":"2026-09-30T02:21:30.939127Z","end":"2026-09-30T02:21:31.11924Z","steps":["trace[1654946923] 'agreement among raft nodes before linearized reading' (duration: 180.032048ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:21:37.278114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.393201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-30T02:21:37.2782Z","caller":"traceutil/trace.go:171","msg":"trace[1956417018] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:650; }","duration":"104.522783ms","start":"2026-09-30T02:21:37.173658Z","end":"2026-09-30T02:21:37.27818Z","steps":["trace[1956417018] 'range keys from in-memory index tree' (duration: 104.245708ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:21:38.266025Z","caller":"traceutil/trace.go:171","msg":"trace[1113929258] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:672; }","duration":"465.607669ms","start":"2026-09-30T02:21:37.800352Z","end":"2026-09-30T02:21:38.26596Z","steps":["trace[1113929258] 'read index received' (duration: 457.745625ms)","trace[1113929258] 'applied index is now lower than readState.Index' (duration: 7.860884ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:21:38.266035Z","caller":"traceutil/trace.go:171","msg":"trace[1876406607] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"545.518852ms","start":"2026-09-30T02:21:37.720471Z","end":"2026-09-30T02:21:38.26599Z","steps":["trace[1876406607] 'process raft request' (duration: 543.561861ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:21:38.266311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.952864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-c9vv7\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-30T02:21:38.26648Z","caller":"traceutil/trace.go:171","msg":"trace[1763890595] range","detail":"{range_begin:/registry/pods/kube-system/cilium-c9vv7; range_end:; response_count:1; response_revision:654; }","duration":"466.166407ms","start":"2026-09-30T02:21:37.800296Z","end":"2026-09-30T02:21:38.266463Z","steps":["trace[1763890595] 'agreement among raft nodes before linearized reading' (duration: 465.759821ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:21:38.266519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:21:37.800279Z","time spent":"466.229238ms","remote":"127.0.0.1:44468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-c9vv7\" "} {"level":"warn","ts":"2026-09-30T02:21:38.26673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:21:37.720451Z","time spent":"545.752345ms","remote":"127.0.0.1:44364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T02:21:38.278229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.114033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-30T02:21:38.278433Z","caller":"traceutil/trace.go:171","msg":"trace[529595186] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"127.357537ms","start":"2026-09-30T02:21:38.151052Z","end":"2026-09-30T02:21:38.278409Z","steps":["trace[529595186] 'agreement among raft nodes before linearized reading' (duration: 127.069432ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:21:55.500975Z","caller":"traceutil/trace.go:171","msg":"trace[1964370729] linearizableReadLoop","detail":"{readStateIndex:847; appliedIndex:846; }","duration":"104.19909ms","start":"2026-09-30T02:21:55.396752Z","end":"2026-09-30T02:21:55.500952Z","steps":["trace[1964370729] 'read index received' (duration: 103.963107ms)","trace[1964370729] 'applied index is now lower than readState.Index' (duration: 234.903µs)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:21:55.501062Z","caller":"traceutil/trace.go:171","msg":"trace[2143701898] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"127.341447ms","start":"2026-09-30T02:21:55.373699Z","end":"2026-09-30T02:21:55.50104Z","steps":["trace[2143701898] 'process raft request' (duration: 127.081194ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:21:55.501277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.737123ms","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-30T02:21:55.501318Z","caller":"traceutil/trace.go:171","msg":"trace[2107084873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:822; }","duration":"103.822265ms","start":"2026-09-30T02:21:55.397484Z","end":"2026-09-30T02:21:55.501307Z","steps":["trace[2107084873] 'agreement among raft nodes before linearized reading' (duration: 103.601172ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:21:55.501577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.768538ms","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-30T02:21:55.501687Z","caller":"traceutil/trace.go:171","msg":"trace[707357127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:822; }","duration":"104.958361ms","start":"2026-09-30T02:21:55.396702Z","end":"2026-09-30T02:21:55.50166Z","steps":["trace[707357127] 'agreement among raft nodes before linearized reading' (duration: 104.372503ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:21:55.711397Z","caller":"traceutil/trace.go:171","msg":"trace[2086926082] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"206.011017ms","start":"2026-09-30T02:21:55.505358Z","end":"2026-09-30T02:21:55.711369Z","steps":["trace[2086926082] 'process raft request' (duration: 181.001194ms)","trace[2086926082] 'compare' (duration: 24.871371ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:21:55.714049Z","caller":"traceutil/trace.go:171","msg":"trace[653192918] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"208.583632ms","start":"2026-09-30T02:21:55.505442Z","end":"2026-09-30T02:21:55.714026Z","steps":["trace[653192918] 'process raft request' (duration: 208.40148ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:21:55.714148Z","caller":"traceutil/trace.go:171","msg":"trace[1006014214] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"125.793867ms","start":"2026-09-30T02:21:55.588338Z","end":"2026-09-30T02:21:55.714132Z","steps":["trace[1006014214] 'process raft request' (duration: 125.625764ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:22:09.860894Z","caller":"traceutil/trace.go:171","msg":"trace[68953465] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"162.552575ms","start":"2026-09-30T02:22:09.698323Z","end":"2026-09-30T02:22:09.860875Z","steps":["trace[68953465] 'process raft request' (duration: 162.176781ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:22:10.06238Z","caller":"traceutil/trace.go:171","msg":"trace[1876831066] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1014; }","duration":"190.021704ms","start":"2026-09-30T02:22:09.872338Z","end":"2026-09-30T02:22:10.06236Z","steps":["trace[1876831066] 'read index received' (duration: 640.308µs)","trace[1876831066] 'applied index is now lower than readState.Index' (duration: 189.380476ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:22:10.062413Z","caller":"traceutil/trace.go:171","msg":"trace[1222813178] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"194.384518ms","start":"2026-09-30T02:22:09.867986Z","end":"2026-09-30T02:22:10.062371Z","steps":["trace[1222813178] 'process raft request' (duration: 193.974093ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:22:10.062446Z","caller":"traceutil/trace.go:171","msg":"trace[107059905] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"193.245674ms","start":"2026-09-30T02:22:09.869178Z","end":"2026-09-30T02:22:10.062423Z","steps":["trace[107059905] 'process raft request' (duration: 193.112952ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:22:10.062583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.225656ms","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-30T02:22:10.062622Z","caller":"traceutil/trace.go:171","msg":"trace[239827243] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:991; }","duration":"190.340117ms","start":"2026-09-30T02:22:09.872275Z","end":"2026-09-30T02:22:10.062615Z","steps":["trace[239827243] 'agreement among raft nodes before linearized reading' (duration: 190.212775ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:22:10.062478Z","caller":"traceutil/trace.go:171","msg":"trace[745827360] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"190.57625ms","start":"2026-09-30T02:22:09.871896Z","end":"2026-09-30T02:22:10.062472Z","steps":["trace[745827360] 'process raft request' (duration: 190.426878ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:22:10.062962Z","caller":"traceutil/trace.go:171","msg":"trace[339712274] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"194.912534ms","start":"2026-09-30T02:22:09.868044Z","end":"2026-09-30T02:22:10.062957Z","steps":["trace[339712274] 'process raft request' (duration: 194.173815ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:22:10.183634Z","caller":"traceutil/trace.go:171","msg":"trace[1446028607] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"112.947478ms","start":"2026-09-30T02:22:10.070574Z","end":"2026-09-30T02:22:10.183522Z","steps":["trace[1446028607] 'process raft request' (duration: 96.246822ms)","trace[1446028607] 'compare' (duration: 16.381852ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:22:10.18643Z","caller":"traceutil/trace.go:171","msg":"trace[1327637132] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"114.259755ms","start":"2026-09-30T02:22:10.072131Z","end":"2026-09-30T02:22:10.186391Z","steps":["trace[1327637132] 'process raft request' (duration: 113.941151ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:22:10.18681Z","caller":"traceutil/trace.go:171","msg":"trace[1318830439] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"114.412177ms","start":"2026-09-30T02:22:10.072376Z","end":"2026-09-30T02:22:10.186788Z","steps":["trace[1318830439] 'process raft request' (duration: 113.90625ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:23:59.230675Z","caller":"traceutil/trace.go:171","msg":"trace[1505155702] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"162.410721ms","start":"2026-09-30T02:23:59.068225Z","end":"2026-09-30T02:23:59.230636Z","steps":["trace[1505155702] 'process raft request' (duration: 155.826876ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:24:00.60786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.356776ms","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-30T02:24:00.607989Z","caller":"traceutil/trace.go:171","msg":"trace[2076102220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1401; }","duration":"180.560958ms","start":"2026-09-30T02:24:00.427396Z","end":"2026-09-30T02:24:00.607957Z","steps":["trace[2076102220] 'range keys from in-memory index tree' (duration: 180.017833ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:24:00.60786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.305658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-30T02:24:00.608612Z","caller":"traceutil/trace.go:171","msg":"trace[912570325] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1401; }","duration":"470.141245ms","start":"2026-09-30T02:24:00.138453Z","end":"2026-09-30T02:24:00.608595Z","steps":["trace[912570325] 'range keys from in-memory index tree' (duration: 469.042536ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:24:00.608675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:24:00.13842Z","time spent":"470.237095ms","remote":"127.0.0.1:44546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-30T02:24:00.904658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.089066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-30T02:24:00.904849Z","caller":"traceutil/trace.go:171","msg":"trace[1064531530] linearizableReadLoop","detail":"{readStateIndex:1455; appliedIndex:1454; }","duration":"244.114741ms","start":"2026-09-30T02:24:00.660685Z","end":"2026-09-30T02:24:00.9048Z","steps":["trace[1064531530] 'read index received' (duration: 87.679472ms)","trace[1064531530] 'applied index is now lower than readState.Index' (duration: 156.433649ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:24:00.904943Z","caller":"traceutil/trace.go:171","msg":"trace[2026458128] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"291.677173ms","start":"2026-09-30T02:24:00.613253Z","end":"2026-09-30T02:24:00.90493Z","steps":["trace[2026458128] 'process raft request' (duration: 135.176774ms)","trace[2026458128] 'compare' (duration: 155.856404ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T02:24:00.90534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.662317ms","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-30T02:24:00.905378Z","caller":"traceutil/trace.go:171","msg":"trace[442342243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1403; }","duration":"244.751047ms","start":"2026-09-30T02:24:00.660616Z","end":"2026-09-30T02:24:00.905367Z","steps":["trace[442342243] 'agreement among raft nodes before linearized reading' (duration: 244.577936ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:24:01.405297Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11622841691480504946,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-30T02:24:01.653323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.910651ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-30T02:24:01.65369Z","caller":"traceutil/trace.go:171","msg":"trace[856371623] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"821.787113ms","start":"2026-09-30T02:24:00.831878Z","end":"2026-09-30T02:24:01.653665Z","steps":["trace[856371623] 'process raft request' (duration: 692.333878ms)","trace[856371623] 'compare' (duration: 128.083514ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T02:24:01.654053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:24:00.831846Z","time spent":"822.111126ms","remote":"127.0.0.1:44546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-30T02:24:01.719371Z","caller":"traceutil/trace.go:171","msg":"trace[979940254] linearizableReadLoop","detail":"{readStateIndex:1457; appliedIndex:1455; }","duration":"814.456082ms","start":"2026-09-30T02:24:00.904878Z","end":"2026-09-30T02:24:01.719334Z","steps":["trace[979940254] 'read index received' (duration: 619.354907ms)","trace[979940254] 'applied index is now lower than readState.Index' (duration: 195.099575ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:24:01.719601Z","caller":"traceutil/trace.go:171","msg":"trace[1169797053] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"807.794717ms","start":"2026-09-30T02:24:00.911696Z","end":"2026-09-30T02:24:01.719491Z","steps":["trace[1169797053] 'process raft request' (duration: 807.434234ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:24:01.719759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.505176ms","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-30T02:24:01.719812Z","caller":"traceutil/trace.go:171","msg":"trace[1133950043] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1405; }","duration":"994.653877ms","start":"2026-09-30T02:24:00.725145Z","end":"2026-09-30T02:24:01.719799Z","steps":["trace[1133950043] 'agreement among raft nodes before linearized reading' (duration: 994.387985ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:24:01.719858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:24:00.725109Z","time spent":"994.739208ms","remote":"127.0.0.1:44546","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":"warn","ts":"2026-09-30T02:24:01.719887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:24:00.911668Z","time spent":"808.078369ms","remote":"127.0.0.1:44546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T02:24:01.719871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.85384ms","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-09-30T02:24:01.719979Z","caller":"traceutil/trace.go:171","msg":"trace[2057378275] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1405; }","duration":"603.029401ms","start":"2026-09-30T02:24:01.116924Z","end":"2026-09-30T02:24:01.719953Z","steps":["trace[2057378275] 'agreement among raft nodes before linearized reading' (duration: 602.750829ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:24:01.72006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:24:01.116895Z","time spent":"603.155192ms","remote":"127.0.0.1:44546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-30T02:24:01.720099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.352021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-30T02:24:01.7202Z","caller":"traceutil/trace.go:171","msg":"trace[546158860] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1405; }","duration":"565.500012ms","start":"2026-09-30T02:24:01.154678Z","end":"2026-09-30T02:24:01.720178Z","steps":["trace[546158860] 'agreement among raft nodes before linearized reading' (duration: 565.29457ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:24:01.720252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:24:01.154656Z","time spent":"565.587373ms","remote":"127.0.0.1:44270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-30T02:24:01.720251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.514442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-30T02:24:01.72047Z","caller":"traceutil/trace.go:171","msg":"trace[890680667] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1405; }","duration":"266.789005ms","start":"2026-09-30T02:24:01.453664Z","end":"2026-09-30T02:24:01.720453Z","steps":["trace[890680667] 'agreement among raft nodes before linearized reading' (duration: 266.339841ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:24:01.923568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.049787ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-30T02:24:01.923735Z","caller":"traceutil/trace.go:171","msg":"trace[2047018228] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"199.026947ms","start":"2026-09-30T02:24:01.724655Z","end":"2026-09-30T02:24:01.923682Z","steps":["trace[2047018228] 'process raft request' (duration: 44.765898ms)","trace[2047018228] 'compare' (duration: 153.928467ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:24:01.923784Z","caller":"traceutil/trace.go:171","msg":"trace[290364730] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"196.040353ms","start":"2026-09-30T02:24:01.727725Z","end":"2026-09-30T02:24:01.923765Z","steps":["trace[290364730] 'process raft request' (duration: 195.955902ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:24:01.923951Z","caller":"traceutil/trace.go:171","msg":"trace[1639415439] linearizableReadLoop","detail":"{readStateIndex:1458; appliedIndex:1457; }","duration":"197.084221ms","start":"2026-09-30T02:24:01.726815Z","end":"2026-09-30T02:24:01.9239Z","steps":["trace[1639415439] 'read index received' (duration: 42.57624ms)","trace[1639415439] 'applied index is now lower than readState.Index' (duration: 154.499001ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T02:24:01.924352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.762117ms","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-30T02:24:01.924396Z","caller":"traceutil/trace.go:171","msg":"trace[24734960] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1407; }","duration":"135.884638ms","start":"2026-09-30T02:24:01.7885Z","end":"2026-09-30T02:24:01.924384Z","steps":["trace[24734960] 'agreement among raft nodes before linearized reading' (duration: 135.756127ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:24:01.924082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.271343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-30T02:24:01.924839Z","caller":"traceutil/trace.go:171","msg":"trace[718515324] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1407; }","duration":"198.049229ms","start":"2026-09-30T02:24:01.72675Z","end":"2026-09-30T02:24:01.924799Z","steps":["trace[718515324] 'agreement among raft nodes before linearized reading' (duration: 197.292413ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:24:02.814883Z","caller":"traceutil/trace.go:171","msg":"trace[1458166176] transaction","detail":"{read_only:false; response_revision:1408; number_of_response:1; }","duration":"150.992471ms","start":"2026-09-30T02:24:02.662418Z","end":"2026-09-30T02:24:02.81341Z","steps":["trace[1458166176] 'process raft request' (duration: 150.664318ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:24:03.177135Z","caller":"traceutil/trace.go:171","msg":"trace[180070303] linearizableReadLoop","detail":"{readStateIndex:1463; appliedIndex:1462; }","duration":"175.370918ms","start":"2026-09-30T02:24:03.001731Z","end":"2026-09-30T02:24:03.177102Z","steps":["trace[180070303] 'read index received' (duration: 150.694976ms)","trace[180070303] 'applied index is now lower than readState.Index' (duration: 24.674522ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:24:03.177272Z","caller":"traceutil/trace.go:171","msg":"trace[1817600325] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"196.139249ms","start":"2026-09-30T02:24:02.98111Z","end":"2026-09-30T02:24:03.177249Z","steps":["trace[1817600325] 'process raft request' (duration: 171.404676ms)","trace[1817600325] 'compare' (duration: 24.346339ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T02:24:03.177351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.59198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-09-30T02:24:03.177398Z","caller":"traceutil/trace.go:171","msg":"trace[932475936] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1410; }","duration":"175.701201ms","start":"2026-09-30T02:24:03.001684Z","end":"2026-09-30T02:24:03.177386Z","steps":["trace[932475936] 'agreement among raft nodes before linearized reading' (duration: 175.57235ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:24:05.862518Z","caller":"traceutil/trace.go:171","msg":"trace[941369457] linearizableReadLoop","detail":"{readStateIndex:1476; appliedIndex:1475; }","duration":"125.793088ms","start":"2026-09-30T02:24:05.736704Z","end":"2026-09-30T02:24:05.862497Z","steps":["trace[941369457] 'read index received' (duration: 125.608926ms)","trace[941369457] 'applied index is now lower than readState.Index' (duration: 183.282µs)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:24:05.86261Z","caller":"traceutil/trace.go:171","msg":"trace[794169457] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"173.550959ms","start":"2026-09-30T02:24:05.689021Z","end":"2026-09-30T02:24:05.862572Z","steps":["trace[794169457] 'process raft request' (duration: 173.346997ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:24:05.862688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.97241ms","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-30T02:24:05.862713Z","caller":"traceutil/trace.go:171","msg":"trace[1582188742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1422; }","duration":"126.061951ms","start":"2026-09-30T02:24:05.736645Z","end":"2026-09-30T02:24:05.862707Z","steps":["trace[1582188742] 'agreement among raft nodes before linearized reading' (duration: 125.939239ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:24:08.041497Z","caller":"traceutil/trace.go:171","msg":"trace[1068737282] linearizableReadLoop","detail":"{readStateIndex:1482; appliedIndex:1481; }","duration":"274.168824ms","start":"2026-09-30T02:24:07.767301Z","end":"2026-09-30T02:24:08.04147Z","steps":["trace[1068737282] 'read index received' (duration: 274.028847ms)","trace[1068737282] 'applied index is now lower than readState.Index' (duration: 138.527µs)"],"step_count":2} {"level":"warn","ts":"2026-09-30T02:24:08.04177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.450818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-09-30T02:24:08.041639Z","caller":"traceutil/trace.go:171","msg":"trace[682267319] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"298.273425ms","start":"2026-09-30T02:24:07.743295Z","end":"2026-09-30T02:24:08.041568Z","steps":["trace[682267319] 'process raft request' (duration: 297.993771ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:24:08.041824Z","caller":"traceutil/trace.go:171","msg":"trace[1137805256] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:1428; }","duration":"274.579385ms","start":"2026-09-30T02:24:07.767229Z","end":"2026-09-30T02:24:08.041808Z","steps":["trace[1137805256] 'agreement among raft nodes before linearized reading' (duration: 274.392036ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:24:08.060241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.016381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-30T02:24:08.060606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.290407ms","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-30T02:24:08.060262Z","caller":"traceutil/trace.go:171","msg":"trace[1959079693] transaction","detail":"{read_only:false; response_revision:1429; number_of_response:1; }","duration":"191.933493ms","start":"2026-09-30T02:24:07.868308Z","end":"2026-09-30T02:24:08.060241Z","steps":["trace[1959079693] 'process raft request' (duration: 191.580765ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:24:08.06079Z","caller":"traceutil/trace.go:171","msg":"trace[2088869951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1429; }","duration":"139.493637ms","start":"2026-09-30T02:24:07.921229Z","end":"2026-09-30T02:24:08.060722Z","steps":["trace[2088869951] 'agreement among raft nodes before linearized reading' (duration: 139.045995ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:24:08.061234Z","caller":"traceutil/trace.go:171","msg":"trace[646143829] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1429; }","duration":"166.450742ms","start":"2026-09-30T02:24:07.89415Z","end":"2026-09-30T02:24:08.060601Z","steps":["trace[646143829] 'agreement among raft nodes before linearized reading' (duration: 165.959417ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:24:08.380632Z","caller":"traceutil/trace.go:171","msg":"trace[350570837] linearizableReadLoop","detail":"{readStateIndex:1487; appliedIndex:1486; }","duration":"156.89983ms","start":"2026-09-30T02:24:08.223705Z","end":"2026-09-30T02:24:08.380605Z","steps":["trace[350570837] 'read index received' (duration: 85.635912ms)","trace[350570837] 'applied index is now lower than readState.Index' (duration: 71.262498ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T02:24:08.380911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.189705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-09-30T02:24:08.380957Z","caller":"traceutil/trace.go:171","msg":"trace[427078915] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:1432; }","duration":"157.30096ms","start":"2026-09-30T02:24:08.223642Z","end":"2026-09-30T02:24:08.380943Z","steps":["trace[427078915] 'agreement among raft nodes before linearized reading' (duration: 157.063968ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:24:09.035106Z","caller":"traceutil/trace.go:171","msg":"trace[1731922449] transaction","detail":"{read_only:false; response_revision:1433; number_of_response:1; }","duration":"168.351336ms","start":"2026-09-30T02:24:08.86673Z","end":"2026-09-30T02:24:09.035082Z","steps":["trace[1731922449] 'process raft request' (duration: 167.957876ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:24:10.273055Z","caller":"traceutil/trace.go:171","msg":"trace[1698606267] transaction","detail":"{read_only:false; response_revision:1437; number_of_response:1; }","duration":"106.786931ms","start":"2026-09-30T02:24:10.166233Z","end":"2026-09-30T02:24:10.27302Z","steps":["trace[1698606267] 'process raft request' (duration: 26.881497ms)","trace[1698606267] 'compare' (duration: 79.677013ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:24:10.273285Z","caller":"traceutil/trace.go:171","msg":"trace[359273488] linearizableReadLoop","detail":"{readStateIndex:1493; appliedIndex:1491; }","duration":"105.405272ms","start":"2026-09-30T02:24:10.167843Z","end":"2026-09-30T02:24:10.273248Z","steps":["trace[359273488] 'read index received' (duration: 25.254246ms)","trace[359273488] 'applied index is now lower than readState.Index' (duration: 80.148216ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T02:24:10.273567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.704807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cluster-admin\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-09-30T02:24:10.273626Z","caller":"traceutil/trace.go:171","msg":"trace[755144404] range","detail":"{range_begin:/registry/clusterroles/cluster-admin; range_end:; response_count:1; response_revision:1438; }","duration":"105.812612ms","start":"2026-09-30T02:24:10.167798Z","end":"2026-09-30T02:24:10.273611Z","steps":["trace[755144404] 'agreement among raft nodes before linearized reading' (duration: 105.704147ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:24:10.273754Z","caller":"traceutil/trace.go:171","msg":"trace[391518034] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"107.418843ms","start":"2026-09-30T02:24:10.16632Z","end":"2026-09-30T02:24:10.273739Z","steps":["trace[391518034] 'process raft request' (duration: 106.645784ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:24:13.052225Z","caller":"traceutil/trace.go:171","msg":"trace[830706025] linearizableReadLoop","detail":"{readStateIndex:1501; appliedIndex:1500; }","duration":"177.490784ms","start":"2026-09-30T02:24:12.874709Z","end":"2026-09-30T02:24:13.0522Z","steps":["trace[830706025] 'read index received' (duration: 116.3386ms)","trace[830706025] 'applied index is now lower than readState.Index' (duration: 61.151084ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:24:13.052257Z","caller":"traceutil/trace.go:171","msg":"trace[1196154158] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"182.318191ms","start":"2026-09-30T02:24:12.869909Z","end":"2026-09-30T02:24:13.052228Z","steps":["trace[1196154158] 'process raft request' (duration: 121.192369ms)","trace[1196154158] 'compare' (duration: 60.920022ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T02:24:13.052577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.694053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cilium-operator\" ","response":"range_response_count:1 size:785"} {"level":"info","ts":"2026-09-30T02:24:13.052622Z","caller":"traceutil/trace.go:171","msg":"trace[1467629930] range","detail":"{range_begin:/registry/clusterrolebindings/cilium-operator; range_end:; response_count:1; response_revision:1445; }","duration":"177.955837ms","start":"2026-09-30T02:24:12.874655Z","end":"2026-09-30T02:24:13.052611Z","steps":["trace[1467629930] 'agreement among raft nodes before linearized reading' (duration: 177.678253ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:24:13.052722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.902132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:daemon-set-controller\" ","response":"range_response_count:1 size:958"} {"level":"info","ts":"2026-09-30T02:24:13.052884Z","caller":"traceutil/trace.go:171","msg":"trace[1443578891] range","detail":"{range_begin:/registry/clusterroles/system:controller:daemon-set-controller; range_end:; response_count:1; response_revision:1445; }","duration":"156.006157ms","start":"2026-09-30T02:24:12.896772Z","end":"2026-09-30T02:24:13.052779Z","steps":["trace[1443578891] 'agreement among raft nodes before linearized reading' (duration: 155.880211ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:24:16.426325Z","caller":"traceutil/trace.go:171","msg":"trace[699487985] linearizableReadLoop","detail":"{readStateIndex:1512; appliedIndex:1511; }","duration":"120.950009ms","start":"2026-09-30T02:24:16.305352Z","end":"2026-09-30T02:24:16.426302Z","steps":["trace[699487985] 'read index received' (duration: 95.378951ms)","trace[699487985] 'applied index is now lower than readState.Index' (duration: 25.570098ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T02:24:16.426535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.135928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:daemon-set-controller\" ","response":"range_response_count:1 size:759"} {"level":"warn","ts":"2026-09-30T02:24:16.426534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.688056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:pv-protection-controller\" ","response":"range_response_count:1 size:693"} {"level":"info","ts":"2026-09-30T02:24:16.426593Z","caller":"traceutil/trace.go:171","msg":"trace[643548290] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:daemon-set-controller; range_end:; response_count:1; response_revision:1456; }","duration":"121.255424ms","start":"2026-09-30T02:24:16.305325Z","end":"2026-09-30T02:24:16.426581Z","steps":["trace[643548290] 'agreement among raft nodes before linearized reading' (duration: 121.090906ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:24:16.426611Z","caller":"traceutil/trace.go:171","msg":"trace[22114038] range","detail":"{range_begin:/registry/clusterroles/system:controller:pv-protection-controller; range_end:; response_count:1; response_revision:1456; }","duration":"120.777001ms","start":"2026-09-30T02:24:16.305816Z","end":"2026-09-30T02:24:16.426593Z","steps":["trace[22114038] 'agreement among raft nodes before linearized reading' (duration: 120.636584ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:24:16.426534Z","caller":"traceutil/trace.go:171","msg":"trace[106646270] transaction","detail":"{read_only:false; response_revision:1456; number_of_response:1; }","duration":"121.671114ms","start":"2026-09-30T02:24:16.304696Z","end":"2026-09-30T02:24:16.426367Z","steps":["trace[106646270] 'process raft request' (duration: 96.095176ms)","trace[106646270] 'compare' (duration: 25.341446ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T02:24:16.693622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.805944ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-30T02:24:16.693701Z","caller":"traceutil/trace.go:171","msg":"trace[1412433512] linearizableReadLoop","detail":"{readStateIndex:1513; appliedIndex:1512; }","duration":"263.307236ms","start":"2026-09-30T02:24:16.43038Z","end":"2026-09-30T02:24:16.693687Z","steps":["trace[1412433512] 'read index received' (duration: 159.311292ms)","trace[1412433512] 'applied index is now lower than readState.Index' (duration: 103.994984ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:24:16.693723Z","caller":"traceutil/trace.go:171","msg":"trace[576740719] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"263.590449ms","start":"2026-09-30T02:24:16.430121Z","end":"2026-09-30T02:24:16.693712Z","steps":["trace[576740719] 'process raft request' (duration: 159.618896ms)","trace[576740719] 'compare' (duration: 103.70777ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T02:24:16.693835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.451192ms","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-30T02:24:16.693858Z","caller":"traceutil/trace.go:171","msg":"trace[1054852254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1457; }","duration":"263.506465ms","start":"2026-09-30T02:24:16.430346Z","end":"2026-09-30T02:24:16.693852Z","steps":["trace[1054852254] 'agreement among raft nodes before linearized reading' (duration: 263.41519ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:24:16.693909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.05305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:pvc-protection-controller\" ","response":"range_response_count:1 size:728"} {"level":"warn","ts":"2026-09-30T02:24:16.693909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.113681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:deployment-controller\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-09-30T02:24:16.693967Z","caller":"traceutil/trace.go:171","msg":"trace[805881220] range","detail":"{range_begin:/registry/clusterroles/system:controller:pvc-protection-controller; range_end:; response_count:1; response_revision:1457; }","duration":"163.135564ms","start":"2026-09-30T02:24:16.530818Z","end":"2026-09-30T02:24:16.693954Z","steps":["trace[805881220] 'agreement among raft nodes before linearized reading' (duration: 163.04014ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:24:16.693976Z","caller":"traceutil/trace.go:171","msg":"trace[362026796] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:deployment-controller; range_end:; response_count:1; response_revision:1457; }","duration":"164.235137ms","start":"2026-09-30T02:24:16.529726Z","end":"2026-09-30T02:24:16.693962Z","steps":["trace[362026796] 'agreement among raft nodes before linearized reading' (duration: 164.08546ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:24:20.418876Z","caller":"traceutil/trace.go:171","msg":"trace[1607949352] transaction","detail":"{read_only:false; response_revision:1468; number_of_response:1; }","duration":"104.303554ms","start":"2026-09-30T02:24:20.31455Z","end":"2026-09-30T02:24:20.418854Z","steps":["trace[1607949352] 'process raft request' (duration: 104.153837ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:24:22.651926Z","caller":"traceutil/trace.go:171","msg":"trace[535594353] linearizableReadLoop","detail":"{readStateIndex:1531; appliedIndex:1530; }","duration":"158.143285ms","start":"2026-09-30T02:24:22.49375Z","end":"2026-09-30T02:24:22.651894Z","steps":["trace[535594353] 'read index received' (duration: 157.8324ms)","trace[535594353] 'applied index is now lower than readState.Index' (duration: 309.175µs)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:24:22.651965Z","caller":"traceutil/trace.go:171","msg":"trace[1453717775] transaction","detail":"{read_only:false; response_revision:1474; number_of_response:1; }","duration":"227.438978ms","start":"2026-09-30T02:24:22.424488Z","end":"2026-09-30T02:24:22.651927Z","steps":["trace[1453717775] 'process raft request' (duration: 227.116603ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:24:22.652116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.351425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:kube-controller-manager\" ","response":"range_response_count:1 size:745"} {"level":"info","ts":"2026-09-30T02:24:22.652206Z","caller":"traceutil/trace.go:171","msg":"trace[1901253823] range","detail":"{range_begin:/registry/clusterrolebindings/system:kube-controller-manager; range_end:; response_count:1; response_revision:1474; }","duration":"158.495041ms","start":"2026-09-30T02:24:22.493688Z","end":"2026-09-30T02:24:22.652183Z","steps":["trace[1901253823] 'agreement among raft nodes before linearized reading' (duration: 158.330303ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:24:22.652232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.437815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:1 size:2108"} {"level":"info","ts":"2026-09-30T02:24:22.652341Z","caller":"traceutil/trace.go:171","msg":"trace[329714190] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:1; response_revision:1474; }","duration":"156.605634ms","start":"2026-09-30T02:24:22.495719Z","end":"2026-09-30T02:24:22.652324Z","steps":["trace[329714190] 'agreement among raft nodes before linearized reading' (duration: 156.425595ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:24:22.764885Z","caller":"traceutil/trace.go:171","msg":"trace[1769262328] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"105.056682ms","start":"2026-09-30T02:24:22.659802Z","end":"2026-09-30T02:24:22.764859Z","steps":["trace[1769262328] 'process raft request' (duration: 79.364551ms)","trace[1769262328] 'compare' (duration: 25.528194ms)"],"step_count":2}