{"level":"warn","ts":"2026-08-07T06:19:16.980813Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-07T06:19:16.980932Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.229: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://199.204.45.229:2380","--initial-cluster=instance=https://199.204.45.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.229: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-08-07T06:19:16.981009Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-07T06:19:16.981024Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-08-07T06:19:16.981045Z","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-08-07T06:19:16.981798Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"]} {"level":"info","ts":"2026-08-07T06:19:16.981924Z","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://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.229: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-08-07T06:19:16.988018Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.896576ms"} {"level":"info","ts":"2026-08-07T06:19:16.99847Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ba9ae53cc4335db","cluster-id":"4ad2674607ff1d60"} {"level":"info","ts":"2026-08-07T06:19:16.998573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=()"} {"level":"info","ts":"2026-08-07T06:19:16.998604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 0"} {"level":"info","ts":"2026-08-07T06:19:16.998625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3ba9ae53cc4335db [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-07T06:19:16.998642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 1"} {"level":"info","ts":"2026-08-07T06:19:16.998693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"warn","ts":"2026-08-07T06:19:17.007376Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-07T06:19:17.009024Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-07T06:19:17.01037Z","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-08-07T06:19:17.012825Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3ba9ae53cc4335db","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-07T06:19:17.015905Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3ba9ae53cc4335db","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-07T06:19:17.016104Z","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-08-07T06:19:17.016231Z","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-08-07T06:19:17.016259Z","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-08-07T06:19:17.017514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"info","ts":"2026-08-07T06:19:17.01763Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","added-peer-id":"3ba9ae53cc4335db","added-peer-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-08-07T06:19:17.018256Z","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-08-07T06:19:17.018304Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-08-07T06:19:17.018352Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-08-07T06:19:17.018671Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3ba9ae53cc4335db","initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-07T06:19:17.018711Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-07T06:19:17.599968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db is starting a new election at term 1"} {"level":"info","ts":"2026-08-07T06:19:17.600108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became pre-candidate at term 1"} {"level":"info","ts":"2026-08-07T06:19:17.600153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgPreVoteResp from 3ba9ae53cc4335db at term 1"} {"level":"info","ts":"2026-08-07T06:19:17.600189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became candidate at term 2"} {"level":"info","ts":"2026-08-07T06:19:17.600211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgVoteResp from 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-08-07T06:19:17.600231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became leader at term 2"} {"level":"info","ts":"2026-08-07T06:19:17.600347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ba9ae53cc4335db elected leader 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-08-07T06:19:17.636102Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-07T06:19:17.638951Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-07T06:19:17.642186Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-07T06:19:17.64302Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-07T06:19:17.643078Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-07T06:19:17.64396Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.229:2379"} {"level":"info","ts":"2026-08-07T06:19:17.646405Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-07T06:19:17.656309Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","cluster-version":"3.5"} {"level":"info","ts":"2026-08-07T06:19:17.638924Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3ba9ae53cc4335db","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.229:2379]}","request-path":"/0/members/3ba9ae53cc4335db/attributes","cluster-id":"4ad2674607ff1d60","publish-timeout":"7s"} {"level":"info","ts":"2026-08-07T06:19:17.65664Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-07T06:19:17.656683Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-08-07T06:19:22.470141Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.199625795s","expected-duration":"1s"} {"level":"info","ts":"2026-08-07T06:19:22.470597Z","caller":"traceutil/trace.go:171","msg":"trace[1225471059] linearizableReadLoop","detail":"{readStateIndex:257; appliedIndex:256; }","duration":"390.098947ms","start":"2026-08-07T06:19:22.080475Z","end":"2026-08-07T06:19:22.470574Z","steps":["trace[1225471059] 'read index received' (duration: 389.875132ms)","trace[1225471059] 'applied index is now lower than readState.Index' (duration: 222.965µs)"],"step_count":2} {"level":"info","ts":"2026-08-07T06:19:22.470648Z","caller":"traceutil/trace.go:171","msg":"trace[496182791] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"1.200219399s","start":"2026-08-07T06:19:21.270397Z","end":"2026-08-07T06:19:22.470617Z","steps":["trace[496182791] 'process raft request' (duration: 1.200036195s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:19:22.470911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.22157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:19:22.470974Z","caller":"traceutil/trace.go:171","msg":"trace[1285460281] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:251; }","duration":"390.534077ms","start":"2026-08-07T06:19:22.080427Z","end":"2026-08-07T06:19:22.470961Z","steps":["trace[1285460281] 'agreement among raft nodes before linearized reading' (duration: 390.22599ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:19:22.471046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:19:22.080407Z","time spent":"390.62669ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-07T06:19:22.471334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:19:21.270373Z","time spent":"1.200527957s","remote":"127.0.0.1:45004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3695,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T06:19:24.165898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.690731752s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-xkgwab\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:19:24.165987Z","caller":"traceutil/trace.go:171","msg":"trace[527552872] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-xkgwab; range_end:; response_count:0; response_revision:251; }","duration":"1.690857655s","start":"2026-08-07T06:19:22.475109Z","end":"2026-08-07T06:19:24.165967Z","steps":["trace[527552872] 'range keys from in-memory index tree' (duration: 1.690648971s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:19:24.166027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:19:22.475092Z","time spent":"1.690926816s","remote":"127.0.0.1:44924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-xkgwab\" "} {"level":"warn","ts":"2026-08-07T06:19:24.167449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.566328258s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-07T06:19:24.167522Z","caller":"traceutil/trace.go:171","msg":"trace[1009156349] linearizableReadLoop","detail":"{readStateIndex:258; appliedIndex:257; }","duration":"1.027122075s","start":"2026-08-07T06:19:23.140388Z","end":"2026-08-07T06:19:24.16751Z","steps":["trace[1009156349] 'read index received' (duration: 67.681µs)","trace[1009156349] 'applied index is now lower than readState.Index' (duration: 1.027053174s)"],"step_count":2} {"level":"info","ts":"2026-08-07T06:19:24.167591Z","caller":"traceutil/trace.go:171","msg":"trace[441002396] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"1.691342897s","start":"2026-08-07T06:19:22.476217Z","end":"2026-08-07T06:19:24.16756Z","steps":["trace[441002396] 'process raft request' (duration: 124.200911ms)","trace[441002396] 'compare' (duration: 1.565700573s)"],"step_count":2} {"level":"warn","ts":"2026-08-07T06:19:24.167639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.027249438s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-07T06:19:24.167746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:19:22.476193Z","time spent":"1.691467151s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-07T06:19:24.16781Z","caller":"traceutil/trace.go:171","msg":"trace[1529241621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:252; }","duration":"1.027451863s","start":"2026-08-07T06:19:23.140346Z","end":"2026-08-07T06:19:24.167798Z","steps":["trace[1529241621] 'agreement among raft nodes before linearized reading' (duration: 1.027224448s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:19:24.167849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:19:23.140324Z","time spent":"1.027514145s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-07T06:19:24.490329Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.043323697s","expected-duration":"1s"} {"level":"info","ts":"2026-08-07T06:19:24.491421Z","caller":"traceutil/trace.go:171","msg":"trace[197909964] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"1.044554496s","start":"2026-08-07T06:19:23.446839Z","end":"2026-08-07T06:19:24.491394Z","steps":["trace[197909964] 'process raft request' (duration: 1.043709316s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:19:24.491553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:19:23.446809Z","time spent":"1.044682249s","remote":"127.0.0.1:45020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-07T06:19:24.493907Z","caller":"traceutil/trace.go:171","msg":"trace[1278059501] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"638.86234ms","start":"2026-08-07T06:19:23.855Z","end":"2026-08-07T06:19:24.493862Z","steps":["trace[1278059501] 'process raft request' (duration: 638.564423ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:19:24.494063Z","caller":"traceutil/trace.go:171","msg":"trace[60384917] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"321.247166ms","start":"2026-08-07T06:19:24.172795Z","end":"2026-08-07T06:19:24.494042Z","steps":["trace[60384917] 'process raft request' (duration: 320.871597ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:19:24.494102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:19:23.854974Z","time spent":"639.036774ms","remote":"127.0.0.1:45020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-07T06:19:24.494389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:19:24.172772Z","time spent":"321.37616ms","remote":"127.0.0.1:44924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":525,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-07T06:19:24.494709Z","caller":"traceutil/trace.go:171","msg":"trace[340279548] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"321.648296ms","start":"2026-08-07T06:19:24.173047Z","end":"2026-08-07T06:19:24.494695Z","steps":["trace[340279548] 'process raft request' (duration: 320.806156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:19:24.494778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:19:24.173031Z","time spent":"321.711857ms","remote":"127.0.0.1:44914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-07T06:19:24.495412Z","caller":"traceutil/trace.go:171","msg":"trace[585335981] linearizableReadLoop","detail":"{readStateIndex:260; appliedIndex:258; }","duration":"327.851288ms","start":"2026-08-07T06:19:24.167544Z","end":"2026-08-07T06:19:24.495396Z","steps":["trace[585335981] 'read index received' (duration: 323.115409ms)","trace[585335981] 'applied index is now lower than readState.Index' (duration: 4.734819ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T06:19:24.495458Z","caller":"traceutil/trace.go:171","msg":"trace[1575932803] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"322.206788ms","start":"2026-08-07T06:19:24.173233Z","end":"2026-08-07T06:19:24.495439Z","steps":["trace[1575932803] 'process raft request' (duration: 320.782115ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:19:24.495572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.353976031s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-08-07T06:19:24.495571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:19:24.173221Z","time spent":"322.276241ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T06:19:24.495621Z","caller":"traceutil/trace.go:171","msg":"trace[1799702188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:257; }","duration":"1.354084043s","start":"2026-08-07T06:19:23.141527Z","end":"2026-08-07T06:19:24.495611Z","steps":["trace[1799702188] 'agreement among raft nodes before linearized reading' (duration: 1.353969891s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:19:24.495631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.766121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:19:24.49571Z","caller":"traceutil/trace.go:171","msg":"trace[936222116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:257; }","duration":"322.888874ms","start":"2026-08-07T06:19:24.172798Z","end":"2026-08-07T06:19:24.495687Z","steps":["trace[936222116] 'agreement among raft nodes before linearized reading' (duration: 322.72677ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:19:24.495727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.840853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-08-07T06:19:24.495769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:19:24.17278Z","time spent":"322.980506ms","remote":"127.0.0.1:44858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-07T06:19:24.495778Z","caller":"traceutil/trace.go:171","msg":"trace[19785540] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:257; }","duration":"322.903015ms","start":"2026-08-07T06:19:24.172865Z","end":"2026-08-07T06:19:24.495768Z","steps":["trace[19785540] 'agreement among raft nodes before linearized reading' (duration: 322.822863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:19:24.495811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:19:24.172858Z","time spent":"322.945945ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-07T06:19:24.495891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"985.418988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-07T06:19:24.495658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:19:23.141503Z","time spent":"1.354146914s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-07T06:19:24.496011Z","caller":"traceutil/trace.go:171","msg":"trace[1292325061] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:257; }","duration":"985.818977ms","start":"2026-08-07T06:19:23.510114Z","end":"2026-08-07T06:19:24.495933Z","steps":["trace[1292325061] 'agreement among raft nodes before linearized reading' (duration: 985.342507ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:19:24.496086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:19:23.510096Z","time spent":"985.977821ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-07T06:19:28.033726Z","caller":"traceutil/trace.go:171","msg":"trace[1281417486] linearizableReadLoop","detail":"{readStateIndex:347; appliedIndex:346; }","duration":"127.913558ms","start":"2026-08-07T06:19:27.905791Z","end":"2026-08-07T06:19:28.033704Z","steps":["trace[1281417486] 'read index received' (duration: 127.599871ms)","trace[1281417486] 'applied index is now lower than readState.Index' (duration: 312.497µs)"],"step_count":2} {"level":"warn","ts":"2026-08-07T06:19:28.033874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.062831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-08-07T06:19:28.033912Z","caller":"traceutil/trace.go:171","msg":"trace[575905426] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:337; }","duration":"128.131272ms","start":"2026-08-07T06:19:27.905771Z","end":"2026-08-07T06:19:28.033902Z","steps":["trace[575905426] 'agreement among raft nodes before linearized reading' (duration: 128.02796ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:19:28.034071Z","caller":"traceutil/trace.go:171","msg":"trace[602351145] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"129.983345ms","start":"2026-08-07T06:19:27.904066Z","end":"2026-08-07T06:19:28.034049Z","steps":["trace[602351145] 'process raft request' (duration: 129.426782ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:19:28.695228Z","caller":"traceutil/trace.go:171","msg":"trace[2079431705] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"128.264295ms","start":"2026-08-07T06:19:28.56693Z","end":"2026-08-07T06:19:28.695195Z","steps":["trace[2079431705] 'process raft request' (duration: 128.002799ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:19:28.695285Z","caller":"traceutil/trace.go:171","msg":"trace[336809713] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"125.094393ms","start":"2026-08-07T06:19:28.570136Z","end":"2026-08-07T06:19:28.69523Z","steps":["trace[336809713] 'process raft request' (duration: 125.022441ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:19:28.695421Z","caller":"traceutil/trace.go:171","msg":"trace[456164946] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"127.704181ms","start":"2026-08-07T06:19:28.567683Z","end":"2026-08-07T06:19:28.695387Z","steps":["trace[456164946] 'process raft request' (duration: 127.405535ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:19:28.720085Z","caller":"traceutil/trace.go:171","msg":"trace[1723734043] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"114.179652ms","start":"2026-08-07T06:19:28.605879Z","end":"2026-08-07T06:19:28.720058Z","steps":["trace[1723734043] 'process raft request' (duration: 114.06215ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:19:35.082829Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871266323696029,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-07T06:19:35.371444Z","caller":"traceutil/trace.go:171","msg":"trace[137028740] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:428; }","duration":"789.512425ms","start":"2026-08-07T06:19:34.581903Z","end":"2026-08-07T06:19:35.371416Z","steps":["trace[137028740] 'read index received' (duration: 789.216228ms)","trace[137028740] 'applied index is now lower than readState.Index' (duration: 294.927µs)"],"step_count":2} {"level":"info","ts":"2026-08-07T06:19:35.371543Z","caller":"traceutil/trace.go:171","msg":"trace[650731290] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"796.878164ms","start":"2026-08-07T06:19:34.574644Z","end":"2026-08-07T06:19:35.371522Z","steps":["trace[650731290] 'process raft request' (duration: 796.542707ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:19:35.371687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.769161ms","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-08-07T06:19:35.371689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:19:34.574617Z","time spent":"796.990547ms","remote":"127.0.0.1:45020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7096,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T06:19:35.371732Z","caller":"traceutil/trace.go:171","msg":"trace[255292154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:417; }","duration":"789.848664ms","start":"2026-08-07T06:19:34.581872Z","end":"2026-08-07T06:19:35.371721Z","steps":["trace[255292154] 'agreement among raft nodes before linearized reading' (duration: 789.68779ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:19:35.371773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:19:34.581861Z","time spent":"789.905035ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-07T06:19:35.371857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.587651ms","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-08-07T06:19:35.371887Z","caller":"traceutil/trace.go:171","msg":"trace[51215804] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:417; }","duration":"655.644602ms","start":"2026-08-07T06:19:34.716232Z","end":"2026-08-07T06:19:35.371876Z","steps":["trace[51215804] 'agreement among raft nodes before linearized reading' (duration: 655.574721ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:19:35.371916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:19:34.716212Z","time spent":"655.697054ms","remote":"127.0.0.1:45116","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-08-07T06:19:35.371893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.92882ms","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-08-07T06:19:35.371964Z","caller":"traceutil/trace.go:171","msg":"trace[1409569251] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:417; }","duration":"768.039594ms","start":"2026-08-07T06:19:34.603905Z","end":"2026-08-07T06:19:35.371945Z","steps":["trace[1409569251] 'agreement among raft nodes before linearized reading' (duration: 767.89736ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:19:35.372034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:19:34.603891Z","time spent":"768.133776ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-07T06:19:35.372067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.800546ms","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-08-07T06:19:35.372103Z","caller":"traceutil/trace.go:171","msg":"trace[1228308708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:417; }","duration":"655.889778ms","start":"2026-08-07T06:19:34.716202Z","end":"2026-08-07T06:19:35.372092Z","steps":["trace[1228308708] 'agreement among raft nodes before linearized reading' (duration: 655.773635ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:19:35.37213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:19:34.716161Z","time spent":"655.96323ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-07T06:19:39.597073Z","caller":"traceutil/trace.go:171","msg":"trace[991380623] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"107.812664ms","start":"2026-08-07T06:19:39.489237Z","end":"2026-08-07T06:19:39.59705Z","steps":["trace[991380623] 'process raft request' (duration: 107.63609ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:20:00.06302Z","caller":"traceutil/trace.go:171","msg":"trace[491727022] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"145.319624ms","start":"2026-08-07T06:19:59.917481Z","end":"2026-08-07T06:20:00.062801Z","steps":["trace[491727022] 'process raft request' (duration: 144.487266ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:20:00.078126Z","caller":"traceutil/trace.go:171","msg":"trace[1768760640] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"100.418334ms","start":"2026-08-07T06:19:59.977689Z","end":"2026-08-07T06:20:00.078107Z","steps":["trace[1768760640] 'process raft request' (duration: 100.222569ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:20:05.792167Z","caller":"traceutil/trace.go:171","msg":"trace[163715906] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"176.218973ms","start":"2026-08-07T06:20:05.615923Z","end":"2026-08-07T06:20:05.792142Z","steps":["trace[163715906] 'read index received' (duration: 176.09848ms)","trace[163715906] 'applied index is now lower than readState.Index' (duration: 119.413µs)"],"step_count":2} {"level":"info","ts":"2026-08-07T06:20:05.792273Z","caller":"traceutil/trace.go:171","msg":"trace[2103500750] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"186.578131ms","start":"2026-08-07T06:20:05.605661Z","end":"2026-08-07T06:20:05.792239Z","steps":["trace[2103500750] 'process raft request' (duration: 186.317264ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:05.792497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.838474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gssl2\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-07T06:20:05.79256Z","caller":"traceutil/trace.go:171","msg":"trace[783885027] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gssl2; range_end:; response_count:1; response_revision:655; }","duration":"158.924306ms","start":"2026-08-07T06:20:05.63362Z","end":"2026-08-07T06:20:05.792545Z","steps":["trace[783885027] 'agreement among raft nodes before linearized reading' (duration: 158.763932ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:05.792645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.518104ms","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-08-07T06:20:05.792735Z","caller":"traceutil/trace.go:171","msg":"trace[1471564821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:655; }","duration":"137.643898ms","start":"2026-08-07T06:20:05.655059Z","end":"2026-08-07T06:20:05.792703Z","steps":["trace[1471564821] 'agreement among raft nodes before linearized reading' (duration: 137.392282ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:05.792413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.481718ms","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-08-07T06:20:05.792854Z","caller":"traceutil/trace.go:171","msg":"trace[1300533148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:655; }","duration":"176.945309ms","start":"2026-08-07T06:20:05.61587Z","end":"2026-08-07T06:20:05.792815Z","steps":["trace[1300533148] 'agreement among raft nodes before linearized reading' (duration: 176.413057ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:06.491361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.698015ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-07T06:20:06.491472Z","caller":"traceutil/trace.go:171","msg":"trace[646590973] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"695.581687ms","start":"2026-08-07T06:20:05.795868Z","end":"2026-08-07T06:20:06.49145Z","steps":["trace[646590973] 'process raft request' (duration: 266.657387ms)","trace[646590973] 'compare' (duration: 428.575062ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T06:20:06.491534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:05.795845Z","time spent":"695.661879ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T06:20:06.750476Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871266323696532,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-07T06:20:07.251545Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871266323696532,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-07T06:20:07.70092Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.633095205s","expected-duration":"1s"} {"level":"info","ts":"2026-08-07T06:20:07.70219Z","caller":"traceutil/trace.go:171","msg":"trace[177524773] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"1.89657512s","start":"2026-08-07T06:20:05.805536Z","end":"2026-08-07T06:20:07.702111Z","steps":["trace[177524773] 'process raft request' (duration: 1.896519829s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:07.702345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:05.805522Z","time spent":"1.896745934s","remote":"127.0.0.1:45020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T06:20:07.702432Z","caller":"traceutil/trace.go:171","msg":"trace[335081367] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"1.90575449s","start":"2026-08-07T06:20:05.796638Z","end":"2026-08-07T06:20:07.702393Z","steps":["trace[335081367] 'process raft request' (duration: 1.905366301s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:07.70258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:05.796629Z","time spent":"1.905871553s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T06:20:07.702752Z","caller":"traceutil/trace.go:171","msg":"trace[1165457534] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"1.906341815s","start":"2026-08-07T06:20:05.796399Z","end":"2026-08-07T06:20:07.70274Z","steps":["trace[1165457534] 'process raft request' (duration: 1.905197668s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:07.70282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:05.796378Z","time spent":"1.906414636s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T06:20:07.739502Z","caller":"traceutil/trace.go:171","msg":"trace[1817424692] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"1.001627889s","start":"2026-08-07T06:20:06.737849Z","end":"2026-08-07T06:20:07.739477Z","steps":["trace[1817424692] 'process raft request' (duration: 1.001435545s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:07.739654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:06.737824Z","time spent":"1.001751361s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T06:20:07.739845Z","caller":"traceutil/trace.go:171","msg":"trace[86151888] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:676; }","duration":"1.489596334s","start":"2026-08-07T06:20:06.250132Z","end":"2026-08-07T06:20:07.739729Z","steps":["trace[86151888] 'read index received' (duration: 1.45108442s)","trace[86151888] 'applied index is now lower than readState.Index' (duration: 38.508234ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T06:20:07.739985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.489824019s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-07T06:20:07.740035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.081929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:20:07.740052Z","caller":"traceutil/trace.go:171","msg":"trace[1639189125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"1.489936501s","start":"2026-08-07T06:20:06.250099Z","end":"2026-08-07T06:20:07.740036Z","steps":["trace[1639189125] 'agreement among raft nodes before linearized reading' (duration: 1.489814138s)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:20:07.740081Z","caller":"traceutil/trace.go:171","msg":"trace[490256460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"951.160192ms","start":"2026-08-07T06:20:06.788906Z","end":"2026-08-07T06:20:07.740067Z","steps":["trace[490256460] 'agreement among raft nodes before linearized reading' (duration: 951.07965ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:07.740094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:06.250082Z","time spent":"1.490003423s","remote":"127.0.0.1:44858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-07T06:20:07.740135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:06.788895Z","time spent":"951.228413ms","remote":"127.0.0.1:44864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-07T06:20:09.896873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.386928ms","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-08-07T06:20:09.896952Z","caller":"traceutil/trace.go:171","msg":"trace[1423219730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:661; }","duration":"190.512681ms","start":"2026-08-07T06:20:09.706419Z","end":"2026-08-07T06:20:09.896932Z","steps":["trace[1423219730] 'range keys from in-memory index tree' (duration: 190.214294ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:09.896962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.107475977s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:20:09.897009Z","caller":"traceutil/trace.go:171","msg":"trace[31561327] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"1.107558509s","start":"2026-08-07T06:20:08.789437Z","end":"2026-08-07T06:20:09.896996Z","steps":["trace[31561327] 'range keys from in-memory index tree' (duration: 1.107389195s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:09.89704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:08.789414Z","time spent":"1.107618871s","remote":"127.0.0.1:44858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-07T06:20:09.89695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.400873167s","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-08-07T06:20:09.897106Z","caller":"traceutil/trace.go:171","msg":"trace[1349906908] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:661; }","duration":"1.401072402s","start":"2026-08-07T06:20:08.496011Z","end":"2026-08-07T06:20:09.897084Z","steps":["trace[1349906908] 'range keys from in-memory index tree' (duration: 1.400718684s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:09.897192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:08.495988Z","time spent":"1.401179495s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-07T06:20:09.897247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.841788ms","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-08-07T06:20:09.897318Z","caller":"traceutil/trace.go:171","msg":"trace[1797978629] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:661; }","duration":"190.961631ms","start":"2026-08-07T06:20:09.706338Z","end":"2026-08-07T06:20:09.897299Z","steps":["trace[1797978629] 'range keys from in-memory index tree' (duration: 190.165673ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:10.406474Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871266323696550,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-07T06:20:10.906637Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871266323696550,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-07T06:20:11.052647Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.151170159s","expected-duration":"1s"} {"level":"info","ts":"2026-08-07T06:20:11.053126Z","caller":"traceutil/trace.go:171","msg":"trace[2086210189] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"1.151731393s","start":"2026-08-07T06:20:09.901369Z","end":"2026-08-07T06:20:11.0531Z","steps":["trace[2086210189] 'process raft request' (duration: 1.151527177s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:11.053319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:09.901357Z","time spent":"1.151842665s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T06:20:11.145009Z","caller":"traceutil/trace.go:171","msg":"trace[1404702093] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"1.243457007s","start":"2026-08-07T06:20:09.90152Z","end":"2026-08-07T06:20:11.144977Z","steps":["trace[1404702093] 'process raft request' (duration: 1.243263162s)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:20:11.145091Z","caller":"traceutil/trace.go:171","msg":"trace[1299188872] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"1.243393354s","start":"2026-08-07T06:20:09.901684Z","end":"2026-08-07T06:20:11.145077Z","steps":["trace[1299188872] 'process raft request' (duration: 1.24321046s)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:20:11.145145Z","caller":"traceutil/trace.go:171","msg":"trace[2078714585] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:682; }","duration":"1.238709247s","start":"2026-08-07T06:20:09.906423Z","end":"2026-08-07T06:20:11.145132Z","steps":["trace[2078714585] 'read index received' (duration: 1.14642343s)","trace[2078714585] 'applied index is now lower than readState.Index' (duration: 92.284617ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T06:20:11.145178Z","caller":"traceutil/trace.go:171","msg":"trace[238753400] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"662.553899ms","start":"2026-08-07T06:20:10.48259Z","end":"2026-08-07T06:20:11.145143Z","steps":["trace[238753400] 'process raft request' (duration: 662.405036ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:11.145193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:09.901513Z","time spent":"1.243571589s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T06:20:11.145071Z","caller":"traceutil/trace.go:171","msg":"trace[1009070157] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"1.243325322s","start":"2026-08-07T06:20:09.901723Z","end":"2026-08-07T06:20:11.145048Z","steps":["trace[1009070157] 'process raft request' (duration: 1.24322564s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:11.14524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:09.90166Z","time spent":"1.243502107s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T06:20:11.145301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:09.901696Z","time spent":"1.243579968s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T06:20:11.145341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:10.482566Z","time spent":"662.693403ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T06:20:11.145474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.704488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-07T06:20:11.145535Z","caller":"traceutil/trace.go:171","msg":"trace[1449947593] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:666; }","duration":"456.809511ms","start":"2026-08-07T06:20:10.68871Z","end":"2026-08-07T06:20:11.14552Z","steps":["trace[1449947593] 'agreement among raft nodes before linearized reading' (duration: 456.611105ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:11.145576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:10.688686Z","time spent":"456.881872ms","remote":"127.0.0.1:44880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.229\" "} {"level":"warn","ts":"2026-08-07T06:20:11.145276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.23884677s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:20:11.145819Z","caller":"traceutil/trace.go:171","msg":"trace[1901056492] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:666; }","duration":"1.239346712s","start":"2026-08-07T06:20:09.906406Z","end":"2026-08-07T06:20:11.145753Z","steps":["trace[1901056492] 'agreement among raft nodes before linearized reading' (duration: 1.238815919s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:11.145881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:09.906399Z","time spent":"1.239472446s","remote":"127.0.0.1:44858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-07T06:20:14.239942Z","caller":"traceutil/trace.go:171","msg":"trace[1150432830] linearizableReadLoop","detail":"{readStateIndex:724; appliedIndex:723; }","duration":"142.688733ms","start":"2026-08-07T06:20:14.097224Z","end":"2026-08-07T06:20:14.239912Z","steps":["trace[1150432830] 'read index received' (duration: 142.53752ms)","trace[1150432830] 'applied index is now lower than readState.Index' (duration: 149.933µs)"],"step_count":2} {"level":"warn","ts":"2026-08-07T06:20:14.240119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.877098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:20:14.240273Z","caller":"traceutil/trace.go:171","msg":"trace[352840677] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:702; }","duration":"142.991791ms","start":"2026-08-07T06:20:14.097167Z","end":"2026-08-07T06:20:14.240159Z","steps":["trace[352840677] 'agreement among raft nodes before linearized reading' (duration: 142.867388ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:20:14.240312Z","caller":"traceutil/trace.go:171","msg":"trace[1048917577] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"341.708039ms","start":"2026-08-07T06:20:13.898575Z","end":"2026-08-07T06:20:14.240283Z","steps":["trace[1048917577] 'process raft request' (duration: 341.204677ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:14.240418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:13.898551Z","time spent":"341.804921ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-07T06:20:17.060265Z","caller":"traceutil/trace.go:171","msg":"trace[1345140629] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"115.175942ms","start":"2026-08-07T06:20:16.945066Z","end":"2026-08-07T06:20:17.060241Z","steps":["trace[1345140629] 'process raft request' (duration: 114.930606ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:20:21.338364Z","caller":"traceutil/trace.go:171","msg":"trace[1057230753] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"188.084295ms","start":"2026-08-07T06:20:21.150253Z","end":"2026-08-07T06:20:21.338337Z","steps":["trace[1057230753] 'process raft request' (duration: 99.846941ms)","trace[1057230753] 'compare' (duration: 88.121701ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T06:20:21.364859Z","caller":"traceutil/trace.go:171","msg":"trace[749414875] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"212.26221ms","start":"2026-08-07T06:20:21.152578Z","end":"2026-08-07T06:20:21.36484Z","steps":["trace[749414875] 'process raft request' (duration: 212.165527ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:20:21.425448Z","caller":"traceutil/trace.go:171","msg":"trace[1650459997] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"145.330924ms","start":"2026-08-07T06:20:21.280101Z","end":"2026-08-07T06:20:21.425432Z","steps":["trace[1650459997] 'process raft request' (duration: 145.11908ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:21.426227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.812043ms","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-08-07T06:20:21.426254Z","caller":"traceutil/trace.go:171","msg":"trace[825857459] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:777; }","duration":"100.876684ms","start":"2026-08-07T06:20:21.325371Z","end":"2026-08-07T06:20:21.426248Z","steps":["trace[825857459] 'agreement among raft nodes before linearized reading' (duration: 100.774842ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:20:21.548587Z","caller":"traceutil/trace.go:171","msg":"trace[1843868947] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"117.018025ms","start":"2026-08-07T06:20:21.431526Z","end":"2026-08-07T06:20:21.548544Z","steps":["trace[1843868947] 'process raft request' (duration: 26.404686ms)","trace[1843868947] 'compare' (duration: 90.348153ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T06:20:21.561372Z","caller":"traceutil/trace.go:171","msg":"trace[2054676467] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"119.136093ms","start":"2026-08-07T06:20:21.442221Z","end":"2026-08-07T06:20:21.561357Z","steps":["trace[2054676467] 'process raft request' (duration: 119.057151ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:20:21.562406Z","caller":"traceutil/trace.go:171","msg":"trace[825458048] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"128.616491ms","start":"2026-08-07T06:20:21.433756Z","end":"2026-08-07T06:20:21.562372Z","steps":["trace[825458048] 'process raft request' (duration: 127.368473ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:20:21.562638Z","caller":"traceutil/trace.go:171","msg":"trace[1060526689] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"125.930439ms","start":"2026-08-07T06:20:21.436694Z","end":"2026-08-07T06:20:21.562624Z","steps":["trace[1060526689] 'process raft request' (duration: 124.513967ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:20:23.849809Z","caller":"traceutil/trace.go:171","msg":"trace[1325055819] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"132.210314ms","start":"2026-08-07T06:20:23.717558Z","end":"2026-08-07T06:20:23.849769Z","steps":["trace[1325055819] 'process raft request' (duration: 106.592656ms)","trace[1325055819] 'compare' (duration: 25.413143ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T06:20:25.162242Z","caller":"traceutil/trace.go:171","msg":"trace[788385285] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"412.256928ms","start":"2026-08-07T06:20:24.74996Z","end":"2026-08-07T06:20:25.162217Z","steps":["trace[788385285] 'process raft request' (duration: 412.102944ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:25.162408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:24.749942Z","time spent":"412.37636ms","remote":"127.0.0.1:45020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5035,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T06:20:25.162625Z","caller":"traceutil/trace.go:171","msg":"trace[209529936] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:822; }","duration":"373.414787ms","start":"2026-08-07T06:20:24.789149Z","end":"2026-08-07T06:20:25.162564Z","steps":["trace[209529936] 'read index received' (duration: 373.388146ms)","trace[209529936] 'applied index is now lower than readState.Index' (duration: 17.92µs)"],"step_count":2} {"level":"warn","ts":"2026-08-07T06:20:25.162701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.558089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:20:25.162806Z","caller":"traceutil/trace.go:171","msg":"trace[976458291] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:796; }","duration":"373.704573ms","start":"2026-08-07T06:20:24.789085Z","end":"2026-08-07T06:20:25.162789Z","steps":["trace[976458291] 'agreement among raft nodes before linearized reading' (duration: 373.57292ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:25.162909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:24.789048Z","time spent":"373.817105ms","remote":"127.0.0.1:44858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-07T06:20:29.310191Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871266323696863,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-07T06:20:29.344535Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.236922627s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-07T06:20:29.344713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.735907124s","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-08-07T06:20:29.34478Z","caller":"traceutil/trace.go:171","msg":"trace[754215323] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:818; }","duration":"1.736039587s","start":"2026-08-07T06:20:27.608719Z","end":"2026-08-07T06:20:29.344759Z","steps":["trace[754215323] 'range keys from in-memory index tree' (duration: 1.735719841s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:29.344824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:27.608687Z","time spent":"1.73612653s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-07T06:20:29.344963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.675937888s","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-08-07T06:20:29.344991Z","caller":"traceutil/trace.go:171","msg":"trace[718371189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:818; }","duration":"1.676013329s","start":"2026-08-07T06:20:27.668966Z","end":"2026-08-07T06:20:29.344979Z","steps":["trace[718371189] 'range keys from in-memory index tree' (duration: 1.675807094s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:29.345012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:27.66894Z","time spent":"1.676067771s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-07T06:20:29.345132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.65074868s","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-08-07T06:20:29.345144Z","caller":"traceutil/trace.go:171","msg":"trace[1404776165] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:818; }","duration":"1.650813741s","start":"2026-08-07T06:20:27.694326Z","end":"2026-08-07T06:20:29.34514Z","steps":["trace[1404776165] 'range keys from in-memory index tree' (duration: 1.650620757s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:29.345158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:27.694295Z","time spent":"1.650858252s","remote":"127.0.0.1:45116","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-08-07T06:20:29.345242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.594655623s","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-08-07T06:20:29.345255Z","caller":"traceutil/trace.go:171","msg":"trace[1168238605] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:818; }","duration":"1.594715585s","start":"2026-08-07T06:20:27.750535Z","end":"2026-08-07T06:20:29.345251Z","steps":["trace[1168238605] 'range keys from in-memory index tree' (duration: 1.594533801s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:29.34527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:27.750506Z","time spent":"1.594761126s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-07T06:20:29.345347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.589693369s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-f4blh\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-08-07T06:20:29.34536Z","caller":"traceutil/trace.go:171","msg":"trace[11834815] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-f4blh; range_end:; response_count:1; response_revision:818; }","duration":"1.58974008s","start":"2026-08-07T06:20:27.755614Z","end":"2026-08-07T06:20:29.345355Z","steps":["trace[11834815] 'range keys from in-memory index tree' (duration: 1.589604547s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:29.345373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:27.755594Z","time spent":"1.589774401s","remote":"127.0.0.1:45020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-f4blh\" "} {"level":"warn","ts":"2026-08-07T06:20:29.345442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.556017237s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:20:29.345455Z","caller":"traceutil/trace.go:171","msg":"trace[63348865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:818; }","duration":"1.556072078s","start":"2026-08-07T06:20:27.789379Z","end":"2026-08-07T06:20:29.345451Z","steps":["trace[63348865] 'range keys from in-memory index tree' (duration: 1.555940755s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:29.345466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:27.789358Z","time spent":"1.556105048s","remote":"127.0.0.1:44858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-07T06:20:29.346118Z","caller":"traceutil/trace.go:171","msg":"trace[2094195783] linearizableReadLoop","detail":"{readStateIndex:846; appliedIndex:845; }","duration":"536.361055ms","start":"2026-08-07T06:20:28.80975Z","end":"2026-08-07T06:20:29.346111Z","steps":["trace[2094195783] 'read index received' (duration: 534.880641ms)","trace[2094195783] 'applied index is now lower than readState.Index' (duration: 1.479934ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T06:20:29.346197Z","caller":"traceutil/trace.go:171","msg":"trace[1833839188] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"1.238732599s","start":"2026-08-07T06:20:28.10746Z","end":"2026-08-07T06:20:29.346192Z","steps":["trace[1833839188] 'process raft request' (duration: 1.237211193s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:29.346241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:28.107419Z","time spent":"1.23878882s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T06:20:29.346294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.39425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:20:29.346335Z","caller":"traceutil/trace.go:171","msg":"trace[147910083] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:819; }","duration":"391.477512ms","start":"2026-08-07T06:20:28.954845Z","end":"2026-08-07T06:20:29.346323Z","steps":["trace[147910083] 'agreement among raft nodes before linearized reading' (duration: 391.40785ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:29.346365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:28.954823Z","time spent":"391.533243ms","remote":"127.0.0.1:45172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-07T06:20:29.346375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.637472ms","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-08-07T06:20:29.346394Z","caller":"traceutil/trace.go:171","msg":"trace[113455378] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:819; }","duration":"536.694783ms","start":"2026-08-07T06:20:28.809694Z","end":"2026-08-07T06:20:29.346389Z","steps":["trace[113455378] 'agreement among raft nodes before linearized reading' (duration: 536.662073ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:29.346407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:28.809673Z","time spent":"536.730244ms","remote":"127.0.0.1:44986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-08-07T06:20:29.634784Z","caller":"traceutil/trace.go:171","msg":"trace[162065073] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"284.905726ms","start":"2026-08-07T06:20:29.349811Z","end":"2026-08-07T06:20:29.634717Z","steps":["trace[162065073] 'process raft request' (duration: 274.835054ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:20:29.635264Z","caller":"traceutil/trace.go:171","msg":"trace[613736954] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"284.042446ms","start":"2026-08-07T06:20:29.351058Z","end":"2026-08-07T06:20:29.6351Z","steps":["trace[613736954] 'process raft request' (duration: 283.668218ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:20:29.635924Z","caller":"traceutil/trace.go:171","msg":"trace[266553140] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"283.455243ms","start":"2026-08-07T06:20:29.352455Z","end":"2026-08-07T06:20:29.63591Z","steps":["trace[266553140] 'process raft request' (duration: 282.522872ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:20:29.636647Z","caller":"traceutil/trace.go:171","msg":"trace[915722028] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"283.853642ms","start":"2026-08-07T06:20:29.352774Z","end":"2026-08-07T06:20:29.636628Z","steps":["trace[915722028] 'process raft request' (duration: 282.90246ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:20:29.637637Z","caller":"traceutil/trace.go:171","msg":"trace[1916907361] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"284.6583ms","start":"2026-08-07T06:20:29.352957Z","end":"2026-08-07T06:20:29.637615Z","steps":["trace[1916907361] 'process raft request' (duration: 283.394381ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:20:29.640151Z","caller":"traceutil/trace.go:171","msg":"trace[2034143737] linearizableReadLoop","detail":"{readStateIndex:852; appliedIndex:846; }","duration":"285.595652ms","start":"2026-08-07T06:20:29.35452Z","end":"2026-08-07T06:20:29.640116Z","steps":["trace[2034143737] 'read index received' (duration: 270.377822ms)","trace[2034143737] 'applied index is now lower than readState.Index' (duration: 15.21659ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T06:20:29.64035Z","caller":"traceutil/trace.go:171","msg":"trace[2048363156] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"286.576945ms","start":"2026-08-07T06:20:29.353763Z","end":"2026-08-07T06:20:29.64034Z","steps":["trace[2048363156] 'process raft request' (duration: 283.806051ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:29.640515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.98054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:20:29.640547Z","caller":"traceutil/trace.go:171","msg":"trace[1526792376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:825; }","duration":"286.039921ms","start":"2026-08-07T06:20:29.354498Z","end":"2026-08-07T06:20:29.640538Z","steps":["trace[1526792376] 'agreement among raft nodes before linearized reading' (duration: 285.97971ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:29.640657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.465299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-07T06:20:29.64068Z","caller":"traceutil/trace.go:171","msg":"trace[787557796] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:825; }","duration":"251.51473ms","start":"2026-08-07T06:20:29.38916Z","end":"2026-08-07T06:20:29.640675Z","steps":["trace[787557796] 'agreement among raft nodes before linearized reading' (duration: 251.47446ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:20:43.489374Z","caller":"traceutil/trace.go:171","msg":"trace[1705851172] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"270.596877ms","start":"2026-08-07T06:20:43.218749Z","end":"2026-08-07T06:20:43.489345Z","steps":["trace[1705851172] 'process raft request' (duration: 270.422043ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:43.73028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.570855ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-07T06:20:43.730404Z","caller":"traceutil/trace.go:171","msg":"trace[2068686812] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"236.287105ms","start":"2026-08-07T06:20:43.494093Z","end":"2026-08-07T06:20:43.73038Z","steps":["trace[2068686812] 'process raft request' (duration: 79.486295ms)","trace[2068686812] 'compare' (duration: 156.421172ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T06:20:43.770669Z","caller":"traceutil/trace.go:171","msg":"trace[1873318938] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"273.938396ms","start":"2026-08-07T06:20:43.496704Z","end":"2026-08-07T06:20:43.770642Z","steps":["trace[1873318938] 'process raft request' (duration: 273.839483ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:20:43.770649Z","caller":"traceutil/trace.go:171","msg":"trace[722939889] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"275.179224ms","start":"2026-08-07T06:20:43.495435Z","end":"2026-08-07T06:20:43.770614Z","steps":["trace[722939889] 'process raft request' (duration: 274.99205ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:20:46.020704Z","caller":"traceutil/trace.go:171","msg":"trace[2087230623] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"313.731054ms","start":"2026-08-07T06:20:45.706952Z","end":"2026-08-07T06:20:46.020683Z","steps":["trace[2087230623] 'read index received' (duration: 313.685643ms)","trace[2087230623] 'applied index is now lower than readState.Index' (duration: 44.741µs)"],"step_count":2} {"level":"info","ts":"2026-08-07T06:20:46.020743Z","caller":"traceutil/trace.go:171","msg":"trace[346795001] transaction","detail":"{read_only:false; number_of_response:1; response_revision:961; }","duration":"377.615518ms","start":"2026-08-07T06:20:45.643105Z","end":"2026-08-07T06:20:46.02072Z","steps":["trace[346795001] 'process raft request' (duration: 377.471635ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:46.020948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.974499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-mtvnl\" ","response":"range_response_count:1 size:4884"} {"level":"info","ts":"2026-08-07T06:20:46.02099Z","caller":"traceutil/trace.go:171","msg":"trace[461063161] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-mtvnl; range_end:; response_count:1; response_revision:961; }","duration":"314.0625ms","start":"2026-08-07T06:20:45.706915Z","end":"2026-08-07T06:20:46.020978Z","steps":["trace[461063161] 'agreement among raft nodes before linearized reading' (duration: 313.902317ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:46.02103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:45.7069Z","time spent":"314.123612ms","remote":"127.0.0.1:45020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4906,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-mtvnl\" "} {"level":"warn","ts":"2026-08-07T06:20:46.021027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:20:45.643085Z","time spent":"377.770203ms","remote":"127.0.0.1:40762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":76,"response count":0,"response size":40,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-08-07T06:20:46.025072Z","caller":"traceutil/trace.go:171","msg":"trace[2141067065] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"141.561818ms","start":"2026-08-07T06:20:45.883501Z","end":"2026-08-07T06:20:46.025063Z","steps":["trace[2141067065] 'process raft request' (duration: 141.384354ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:46.02512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.526921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-07T06:20:46.025164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.744024ms","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-08-07T06:20:46.025182Z","caller":"traceutil/trace.go:171","msg":"trace[301092876] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:962; }","duration":"232.797045ms","start":"2026-08-07T06:20:45.792381Z","end":"2026-08-07T06:20:46.025178Z","steps":["trace[301092876] 'agreement among raft nodes before linearized reading' (duration: 232.745834ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:46.025179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.87704ms","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-08-07T06:20:46.025195Z","caller":"traceutil/trace.go:171","msg":"trace[440459892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:962; }","duration":"236.639503ms","start":"2026-08-07T06:20:45.788537Z","end":"2026-08-07T06:20:46.025176Z","steps":["trace[440459892] 'agreement among raft nodes before linearized reading' (duration: 236.4927ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:20:46.02522Z","caller":"traceutil/trace.go:171","msg":"trace[1836450062] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:962; }","duration":"233.933771ms","start":"2026-08-07T06:20:45.791274Z","end":"2026-08-07T06:20:46.025208Z","steps":["trace[1836450062] 'agreement among raft nodes before linearized reading' (duration: 233.806438ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:46.025248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.091974ms","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-08-07T06:20:46.02526Z","caller":"traceutil/trace.go:171","msg":"trace[1566190834] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:962; }","duration":"234.133266ms","start":"2026-08-07T06:20:45.791123Z","end":"2026-08-07T06:20:46.025257Z","steps":["trace[1566190834] 'agreement among raft nodes before linearized reading' (duration: 234.095305ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:20:46.02526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.845939ms","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-08-07T06:20:46.025303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.9124ms","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-08-07T06:20:46.025356Z","caller":"traceutil/trace.go:171","msg":"trace[1722868737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:962; }","duration":"233.993093ms","start":"2026-08-07T06:20:45.791351Z","end":"2026-08-07T06:20:46.025344Z","steps":["trace[1722868737] 'agreement among raft nodes before linearized reading' (duration: 233.751036ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:20:46.025355Z","caller":"traceutil/trace.go:171","msg":"trace[2089447674] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:962; }","duration":"234.006913ms","start":"2026-08-07T06:20:45.791321Z","end":"2026-08-07T06:20:46.025328Z","steps":["trace[2089447674] 'agreement among raft nodes before linearized reading' (duration: 233.728566ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:21:08.645921Z","caller":"traceutil/trace.go:171","msg":"trace[1684399630] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"249.187883ms","start":"2026-08-07T06:21:08.396712Z","end":"2026-08-07T06:21:08.6459Z","steps":["trace[1684399630] 'process raft request' (duration: 248.023767ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:08.646125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.413035ms","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-08-07T06:21:08.646188Z","caller":"traceutil/trace.go:171","msg":"trace[1312547701] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1045; }","duration":"245.512368ms","start":"2026-08-07T06:21:08.400659Z","end":"2026-08-07T06:21:08.646171Z","steps":["trace[1312547701] 'agreement among raft nodes before linearized reading' (duration: 245.327693ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:21:08.646414Z","caller":"traceutil/trace.go:171","msg":"trace[936500355] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1079; }","duration":"245.17138ms","start":"2026-08-07T06:21:08.400695Z","end":"2026-08-07T06:21:08.645867Z","steps":["trace[936500355] 'read index received' (duration: 243.989793ms)","trace[936500355] 'applied index is now lower than readState.Index' (duration: 1.180477ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T06:21:08.647226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.43255ms","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-08-07T06:21:08.64733Z","caller":"traceutil/trace.go:171","msg":"trace[1276695861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1045; }","duration":"246.563653ms","start":"2026-08-07T06:21:08.400754Z","end":"2026-08-07T06:21:08.647318Z","steps":["trace[1276695861] 'agreement among raft nodes before linearized reading' (duration: 245.968119ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:08.647423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.621254ms","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-08-07T06:21:08.647518Z","caller":"traceutil/trace.go:171","msg":"trace[1805141399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1045; }","duration":"246.766418ms","start":"2026-08-07T06:21:08.400729Z","end":"2026-08-07T06:21:08.647496Z","steps":["trace[1805141399] 'agreement among raft nodes before linearized reading' (duration: 245.914158ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:21:17.002854Z","caller":"traceutil/trace.go:171","msg":"trace[1904566222] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"153.741969ms","start":"2026-08-07T06:21:16.849079Z","end":"2026-08-07T06:21:17.002821Z","steps":["trace[1904566222] 'process raft request' (duration: 153.584585ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:21:17.003667Z","caller":"traceutil/trace.go:171","msg":"trace[1342707112] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"154.178679ms","start":"2026-08-07T06:21:16.849462Z","end":"2026-08-07T06:21:17.003641Z","steps":["trace[1342707112] 'process raft request' (duration: 154.074637ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:18.926485Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871266323697423,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-07T06:21:19.085442Z","caller":"traceutil/trace.go:171","msg":"trace[1129897206] linearizableReadLoop","detail":"{readStateIndex:1113; appliedIndex:1112; }","duration":"659.919957ms","start":"2026-08-07T06:21:18.4255Z","end":"2026-08-07T06:21:19.08542Z","steps":["trace[1129897206] 'read index received' (duration: 659.705232ms)","trace[1129897206] 'applied index is now lower than readState.Index' (duration: 213.715µs)"],"step_count":2} {"level":"info","ts":"2026-08-07T06:21:19.085559Z","caller":"traceutil/trace.go:171","msg":"trace[2098019141] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"715.91309ms","start":"2026-08-07T06:21:18.369616Z","end":"2026-08-07T06:21:19.085529Z","steps":["trace[2098019141] 'process raft request' (duration: 715.647803ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:19.085659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.099701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:21:19.085719Z","caller":"traceutil/trace.go:171","msg":"trace[1973949829] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1076; }","duration":"660.248354ms","start":"2026-08-07T06:21:18.425454Z","end":"2026-08-07T06:21:19.085703Z","steps":["trace[1973949829] 'agreement among raft nodes before linearized reading' (duration: 660.098181ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:19.085738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:18.369595Z","time spent":"716.064703ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T06:21:19.085783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:18.425437Z","time spent":"660.325946ms","remote":"127.0.0.1:38648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-08-07T06:21:19.085846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.602161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:21:19.085912Z","caller":"traceutil/trace.go:171","msg":"trace[1918688196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1076; }","duration":"297.690773ms","start":"2026-08-07T06:21:18.788205Z","end":"2026-08-07T06:21:19.085896Z","steps":["trace[1918688196] 'agreement among raft nodes before linearized reading' (duration: 297.55417ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:19.085884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.676908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-07T06:21:19.085898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.088618ms","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-08-07T06:21:19.085971Z","caller":"traceutil/trace.go:171","msg":"trace[1508542081] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1076; }","duration":"360.80539ms","start":"2026-08-07T06:21:18.725149Z","end":"2026-08-07T06:21:19.085954Z","steps":["trace[1508542081] 'agreement among raft nodes before linearized reading' (duration: 360.629776ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:21:19.085976Z","caller":"traceutil/trace.go:171","msg":"trace[1821007344] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1076; }","duration":"627.246842ms","start":"2026-08-07T06:21:18.458716Z","end":"2026-08-07T06:21:19.085962Z","steps":["trace[1821007344] 'agreement among raft nodes before linearized reading' (duration: 626.889534ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:19.086017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:18.725132Z","time spent":"360.877112ms","remote":"127.0.0.1:45116","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-08-07T06:21:19.086041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:18.458702Z","time spent":"627.327814ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-07T06:21:19.086333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.287892ms","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-08-07T06:21:19.086437Z","caller":"traceutil/trace.go:171","msg":"trace[326072501] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1076; }","duration":"248.410605ms","start":"2026-08-07T06:21:18.838016Z","end":"2026-08-07T06:21:19.086426Z","steps":["trace[326072501] 'agreement among raft nodes before linearized reading' (duration: 247.651768ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:19.752773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.510847ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-07T06:21:19.753119Z","caller":"traceutil/trace.go:171","msg":"trace[181517301] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"662.141577ms","start":"2026-08-07T06:21:19.090729Z","end":"2026-08-07T06:21:19.75287Z","steps":["trace[181517301] 'process raft request' (duration: 517.457436ms)","trace[181517301] 'compare' (duration: 144.257991ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T06:21:19.753203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:19.090711Z","time spent":"662.455804ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T06:21:19.994709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.937803ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-07T06:21:19.994898Z","caller":"traceutil/trace.go:171","msg":"trace[694908945] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"904.025602ms","start":"2026-08-07T06:21:19.090855Z","end":"2026-08-07T06:21:19.994881Z","steps":["trace[694908945] 'process raft request' (duration: 903.949881ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:19.994971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:19.090842Z","time spent":"904.096365ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T06:21:19.994977Z","caller":"traceutil/trace.go:171","msg":"trace[1180520192] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"904.169566ms","start":"2026-08-07T06:21:19.090773Z","end":"2026-08-07T06:21:19.994943Z","steps":["trace[1180520192] 'process raft request' (duration: 775.919294ms)","trace[1180520192] 'compare' (duration: 127.846471ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T06:21:19.99512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:19.090759Z","time spent":"904.282568ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T06:21:19.995153Z","caller":"traceutil/trace.go:171","msg":"trace[1585991535] linearizableReadLoop","detail":"{readStateIndex:1118; appliedIndex:1113; }","duration":"410.209242ms","start":"2026-08-07T06:21:19.584907Z","end":"2026-08-07T06:21:19.995116Z","steps":["trace[1585991535] 'read index received' (duration: 23.166144ms)","trace[1585991535] 'applied index is now lower than readState.Index' (duration: 387.042188ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T06:21:19.995269Z","caller":"traceutil/trace.go:171","msg":"trace[384526179] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"904.265598ms","start":"2026-08-07T06:21:19.090971Z","end":"2026-08-07T06:21:19.995236Z","steps":["trace[384526179] 'process raft request' (duration: 904.068924ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:19.995312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:19.090963Z","time spent":"904.32739ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T06:21:19.995318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.389756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:21:19.995359Z","caller":"traceutil/trace.go:171","msg":"trace[2100481232] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1081; }","duration":"410.481798ms","start":"2026-08-07T06:21:19.584865Z","end":"2026-08-07T06:21:19.995346Z","steps":["trace[2100481232] 'agreement among raft nodes before linearized reading' (duration: 410.381326ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:19.995399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:19.584844Z","time spent":"410.544999ms","remote":"127.0.0.1:38720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-07T06:21:19.995416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.387565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:21:19.995441Z","caller":"traceutil/trace.go:171","msg":"trace[1810143796] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1081; }","duration":"206.441457ms","start":"2026-08-07T06:21:19.788992Z","end":"2026-08-07T06:21:19.995433Z","steps":["trace[1810143796] 'agreement among raft nodes before linearized reading' (duration: 206.391996ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:19.995784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.202625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-07T06:21:19.995848Z","caller":"traceutil/trace.go:171","msg":"trace[2024027937] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1081; }","duration":"178.277567ms","start":"2026-08-07T06:21:19.817555Z","end":"2026-08-07T06:21:19.995833Z","steps":["trace[2024027937] 'agreement among raft nodes before linearized reading' (duration: 178.158914ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:21:19.996019Z","caller":"traceutil/trace.go:171","msg":"trace[268472477] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"904.38752ms","start":"2026-08-07T06:21:19.090894Z","end":"2026-08-07T06:21:19.995281Z","steps":["trace[268472477] 'process raft request' (duration: 903.956361ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:19.9961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:19.090863Z","time spent":"905.195729ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T06:21:21.400545Z","caller":"traceutil/trace.go:171","msg":"trace[1453329976] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"308.207426ms","start":"2026-08-07T06:21:21.09229Z","end":"2026-08-07T06:21:21.400497Z","steps":["trace[1453329976] 'process raft request' (duration: 308.068963ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:21.400688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:21.092254Z","time spent":"308.358599ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T06:21:21.664791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.05479ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:21:21.664933Z","caller":"traceutil/trace.go:171","msg":"trace[1745201945] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1084; }","duration":"171.209983ms","start":"2026-08-07T06:21:21.493705Z","end":"2026-08-07T06:21:21.664915Z","steps":["trace[1745201945] 'range keys from in-memory index tree' (duration: 171.037839ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:22.499806Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871266323697446,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-07T06:21:22.594116Z","caller":"traceutil/trace.go:171","msg":"trace[847717404] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"832.91566ms","start":"2026-08-07T06:21:21.761176Z","end":"2026-08-07T06:21:22.594092Z","steps":["trace[847717404] 'process raft request' (duration: 832.782997ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:22.594253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:21.761155Z","time spent":"833.018752ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T06:21:24.051664Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.457533383s","expected-duration":"1s"} {"level":"info","ts":"2026-08-07T06:21:24.052068Z","caller":"traceutil/trace.go:171","msg":"trace[2097675133] linearizableReadLoop","detail":"{readStateIndex:1124; appliedIndex:1123; }","duration":"2.052306934s","start":"2026-08-07T06:21:21.999709Z","end":"2026-08-07T06:21:24.052016Z","steps":["trace[2097675133] 'read index received' (duration: 594.439904ms)","trace[2097675133] 'applied index is now lower than readState.Index' (duration: 1.45786592s)"],"step_count":2} {"level":"info","ts":"2026-08-07T06:21:24.052208Z","caller":"traceutil/trace.go:171","msg":"trace[240809501] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"1.632094233s","start":"2026-08-07T06:21:22.420092Z","end":"2026-08-07T06:21:24.052186Z","steps":["trace[240809501] 'process raft request' (duration: 1.631740895s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:24.052338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:22.420068Z","time spent":"1.632194985s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T06:21:24.052348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.052626181s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-08-07T06:21:24.052419Z","caller":"traceutil/trace.go:171","msg":"trace[2077108856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1086; }","duration":"2.052711873s","start":"2026-08-07T06:21:21.999668Z","end":"2026-08-07T06:21:24.05238Z","steps":["trace[2077108856] 'agreement among raft nodes before linearized reading' (duration: 2.052514209s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:24.052488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:21.999649Z","time spent":"2.052828237s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-07T06:21:24.082979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.293805121s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-07T06:21:24.083024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.082062761s","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-08-07T06:21:24.083056Z","caller":"traceutil/trace.go:171","msg":"trace[190198091] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1087; }","duration":"1.293925054s","start":"2026-08-07T06:21:22.789113Z","end":"2026-08-07T06:21:24.083038Z","steps":["trace[190198091] 'agreement among raft nodes before linearized reading' (duration: 1.293803261s)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:21:24.08307Z","caller":"traceutil/trace.go:171","msg":"trace[1450307253] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1087; }","duration":"2.082124663s","start":"2026-08-07T06:21:22.000932Z","end":"2026-08-07T06:21:24.083057Z","steps":["trace[1450307253] 'agreement among raft nodes before linearized reading' (duration: 2.0820034s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:24.083102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:22.789055Z","time spent":"1.294038977s","remote":"127.0.0.1:44858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-07T06:21:24.083154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.787808397s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:21:24.083192Z","caller":"traceutil/trace.go:171","msg":"trace[1656385771] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1087; }","duration":"1.787962021s","start":"2026-08-07T06:21:22.295221Z","end":"2026-08-07T06:21:24.083183Z","steps":["trace[1656385771] 'agreement among raft nodes before linearized reading' (duration: 1.787906919s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:24.083219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:22.295199Z","time spent":"1.788012881s","remote":"127.0.0.1:38558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-08-07T06:21:24.083524Z","caller":"traceutil/trace.go:171","msg":"trace[255567428] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"677.926802ms","start":"2026-08-07T06:21:23.405583Z","end":"2026-08-07T06:21:24.08351Z","steps":["trace[255567428] 'process raft request' (duration: 677.153954ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:24.08311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:22.000927Z","time spent":"2.082176404s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-07T06:21:24.083609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.083025773s","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-08-07T06:21:24.08365Z","caller":"traceutil/trace.go:171","msg":"trace[211868608] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1087; }","duration":"2.083081875s","start":"2026-08-07T06:21:22.000558Z","end":"2026-08-07T06:21:24.08364Z","steps":["trace[211868608] 'agreement among raft nodes before linearized reading' (duration: 2.082978093s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:24.083684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:22.000553Z","time spent":"2.083124416s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-07T06:21:24.08378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.27249312s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-07T06:21:24.083826Z","caller":"traceutil/trace.go:171","msg":"trace[1876128874] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1087; }","duration":"1.272601162s","start":"2026-08-07T06:21:22.811213Z","end":"2026-08-07T06:21:24.083814Z","steps":["trace[1876128874] 'agreement among raft nodes before linearized reading' (duration: 1.27250831s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:24.083894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:22.811178Z","time spent":"1.272667894s","remote":"127.0.0.1:45382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-08-07T06:21:24.083934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:23.40556Z","time spent":"677.997064ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T06:21:24.084083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.083564366s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-07T06:21:24.084106Z","caller":"traceutil/trace.go:171","msg":"trace[1589139247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1087; }","duration":"2.083604157s","start":"2026-08-07T06:21:22.000496Z","end":"2026-08-07T06:21:24.0841Z","steps":["trace[1589139247] 'agreement among raft nodes before linearized reading' (duration: 2.08238332s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:24.08412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:22.000487Z","time spent":"2.083629638s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-07T06:21:24.243447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.995844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-07T06:21:24.243651Z","caller":"traceutil/trace.go:171","msg":"trace[1015666664] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1126; }","duration":"134.122897ms","start":"2026-08-07T06:21:24.109512Z","end":"2026-08-07T06:21:24.243635Z","steps":["trace[1015666664] 'read index received' (duration: 22.798096ms)","trace[1015666664] 'applied index is now lower than readState.Index' (duration: 111.323961ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T06:21:24.243675Z","caller":"traceutil/trace.go:171","msg":"trace[1323341607] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"155.866608ms","start":"2026-08-07T06:21:24.087763Z","end":"2026-08-07T06:21:24.24363Z","steps":["trace[1323341607] 'process raft request' (duration: 44.609749ms)","trace[1323341607] 'compare' (duration: 110.85959ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T06:21:24.243733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.217919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:21:24.243758Z","caller":"traceutil/trace.go:171","msg":"trace[856759038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1091; }","duration":"134.261201ms","start":"2026-08-07T06:21:24.109489Z","end":"2026-08-07T06:21:24.24375Z","steps":["trace[856759038] 'agreement among raft nodes before linearized reading' (duration: 134.192119ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:21:24.243795Z","caller":"traceutil/trace.go:171","msg":"trace[63958165] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"155.850038ms","start":"2026-08-07T06:21:24.087922Z","end":"2026-08-07T06:21:24.243772Z","steps":["trace[63958165] 'process raft request' (duration: 155.613643ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:21:24.243993Z","caller":"traceutil/trace.go:171","msg":"trace[359319112] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"154.766753ms","start":"2026-08-07T06:21:24.089215Z","end":"2026-08-07T06:21:24.243982Z","steps":["trace[359319112] 'process raft request' (duration: 154.382224ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:21:25.178379Z","caller":"traceutil/trace.go:171","msg":"trace[1995144782] linearizableReadLoop","detail":"{readStateIndex:1130; appliedIndex:1129; }","duration":"206.837606ms","start":"2026-08-07T06:21:24.97152Z","end":"2026-08-07T06:21:25.178358Z","steps":["trace[1995144782] 'read index received' (duration: 206.668962ms)","trace[1995144782] 'applied index is now lower than readState.Index' (duration: 167.724µs)"],"step_count":2} {"level":"info","ts":"2026-08-07T06:21:25.178536Z","caller":"traceutil/trace.go:171","msg":"trace[752587627] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"576.011819ms","start":"2026-08-07T06:21:24.602472Z","end":"2026-08-07T06:21:25.178484Z","steps":["trace[752587627] 'process raft request' (duration: 575.762684ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:25.178574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.0354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-07T06:21:25.178574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.224091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-07T06:21:25.178625Z","caller":"traceutil/trace.go:171","msg":"trace[1828555344] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1092; }","duration":"154.314682ms","start":"2026-08-07T06:21:25.024297Z","end":"2026-08-07T06:21:25.178612Z","steps":["trace[1828555344] 'agreement among raft nodes before linearized reading' (duration: 154.20392ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:21:25.178636Z","caller":"traceutil/trace.go:171","msg":"trace[1867417211] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1092; }","duration":"207.121272ms","start":"2026-08-07T06:21:24.971484Z","end":"2026-08-07T06:21:25.178605Z","steps":["trace[1867417211] 'agreement among raft nodes before linearized reading' (duration: 206.982259ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:25.178876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:24.60245Z","time spent":"576.209864ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T06:21:26.605708Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871266323697463,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-07T06:21:26.94676Z","caller":"traceutil/trace.go:171","msg":"trace[2139735915] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"857.422516ms","start":"2026-08-07T06:21:26.089236Z","end":"2026-08-07T06:21:26.946658Z","steps":["trace[2139735915] 'process raft request' (duration: 857.250922ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:26.946913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:26.089213Z","time spent":"857.623811ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T06:21:28.856722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.399137174s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-07T06:21:28.856804Z","caller":"traceutil/trace.go:171","msg":"trace[1830434549] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1130; }","duration":"2.752032839s","start":"2026-08-07T06:21:26.104755Z","end":"2026-08-07T06:21:28.856787Z","steps":["trace[1830434549] 'read index received' (duration: 841.743834ms)","trace[1830434549] 'applied index is now lower than readState.Index' (duration: 1.910287765s)"],"step_count":2} {"level":"warn","ts":"2026-08-07T06:21:28.857021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.752268425s","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-08-07T06:21:28.857049Z","caller":"traceutil/trace.go:171","msg":"trace[1309779801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1093; }","duration":"2.752338176s","start":"2026-08-07T06:21:26.104704Z","end":"2026-08-07T06:21:28.857042Z","steps":["trace[1309779801] 'agreement among raft nodes before linearized reading' (duration: 2.752227473s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:28.857078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:26.104681Z","time spent":"2.752384117s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-07T06:21:28.857049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.60738289s","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-08-07T06:21:28.857086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.673346014s","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-08-07T06:21:28.857126Z","caller":"traceutil/trace.go:171","msg":"trace[549783277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1093; }","duration":"2.607487712s","start":"2026-08-07T06:21:26.249623Z","end":"2026-08-07T06:21:28.857111Z","steps":["trace[549783277] 'agreement among raft nodes before linearized reading' (duration: 2.607333219s)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:21:28.857141Z","caller":"traceutil/trace.go:171","msg":"trace[366808464] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1093; }","duration":"1.673446657s","start":"2026-08-07T06:21:27.183681Z","end":"2026-08-07T06:21:28.857127Z","steps":["trace[366808464] 'agreement among raft nodes before linearized reading' (duration: 1.673278543s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:28.857142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.606596162s","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":"warn","ts":"2026-08-07T06:21:28.85716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:26.249605Z","time spent":"2.607547134s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-07T06:21:28.857171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:27.18366Z","time spent":"1.673505208s","remote":"127.0.0.1:45116","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-08-07T06:21:28.857232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.380692066s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:21:28.857269Z","caller":"traceutil/trace.go:171","msg":"trace[530697531] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1093; }","duration":"2.380769318s","start":"2026-08-07T06:21:26.476491Z","end":"2026-08-07T06:21:28.85726Z","steps":["trace[530697531] 'agreement among raft nodes before linearized reading' (duration: 2.380716256s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:28.8573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:26.476472Z","time spent":"2.380819409s","remote":"127.0.0.1:40806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-08-07T06:21:28.857311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.674961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:21:28.857331Z","caller":"traceutil/trace.go:171","msg":"trace[1055875192] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1093; }","duration":"572.740403ms","start":"2026-08-07T06:21:28.284585Z","end":"2026-08-07T06:21:28.857325Z","steps":["trace[1055875192] 'agreement among raft nodes before linearized reading' (duration: 572.700842ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:28.857337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.607112954s","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-08-07T06:21:28.857379Z","caller":"traceutil/trace.go:171","msg":"trace[1309457471] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1093; }","duration":"2.607202336s","start":"2026-08-07T06:21:26.250165Z","end":"2026-08-07T06:21:28.857367Z","steps":["trace[1309457471] 'agreement among raft nodes before linearized reading' (duration: 2.607089953s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:28.85739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.161056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:21:28.857412Z","caller":"traceutil/trace.go:171","msg":"trace[2003482273] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1093; }","duration":"613.219208ms","start":"2026-08-07T06:21:28.244187Z","end":"2026-08-07T06:21:28.857406Z","steps":["trace[2003482273] 'agreement among raft nodes before linearized reading' (duration: 613.186037ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:28.857412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:26.25014Z","time spent":"2.607262928s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-07T06:21:28.857185Z","caller":"traceutil/trace.go:171","msg":"trace[1473909387] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1093; }","duration":"2.606817537s","start":"2026-08-07T06:21:26.250355Z","end":"2026-08-07T06:21:28.857173Z","steps":["trace[1473909387] 'agreement among raft nodes before linearized reading' (duration: 2.606692444s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:28.857429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:28.244167Z","time spent":"613.257019ms","remote":"127.0.0.1:38760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-08-07T06:21:28.857429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070388783s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:21:28.857451Z","caller":"traceutil/trace.go:171","msg":"trace[1612027967] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1093; }","duration":"1.070413224s","start":"2026-08-07T06:21:27.787032Z","end":"2026-08-07T06:21:28.857445Z","steps":["trace[1612027967] 'agreement among raft nodes before linearized reading' (duration: 1.070375883s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:28.857454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:26.250346Z","time spent":"2.607098253s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-07T06:21:28.857468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:27.786984Z","time spent":"1.070480405s","remote":"127.0.0.1:44858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-07T06:21:28.85735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:28.284564Z","time spent":"572.781644ms","remote":"127.0.0.1:44958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-08-07T06:21:28.970171Z","caller":"traceutil/trace.go:171","msg":"trace[2047197332] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"108.551386ms","start":"2026-08-07T06:21:28.861599Z","end":"2026-08-07T06:21:28.970151Z","steps":["trace[2047197332] 'process raft request' (duration: 108.405142ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:21:29.185159Z","caller":"traceutil/trace.go:171","msg":"trace[790705605] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"323.349245ms","start":"2026-08-07T06:21:28.861787Z","end":"2026-08-07T06:21:29.185137Z","steps":["trace[790705605] 'process raft request' (duration: 323.183412ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:29.185291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:28.861768Z","time spent":"323.457427ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T06:21:29.185473Z","caller":"traceutil/trace.go:171","msg":"trace[20748182] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"323.631291ms","start":"2026-08-07T06:21:28.861806Z","end":"2026-08-07T06:21:29.185438Z","steps":["trace[20748182] 'process raft request' (duration: 323.294114ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:21:29.185505Z","caller":"traceutil/trace.go:171","msg":"trace[1593598371] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1132; }","duration":"321.592135ms","start":"2026-08-07T06:21:28.863891Z","end":"2026-08-07T06:21:29.185483Z","steps":["trace[1593598371] 'read index received' (duration: 106.142801ms)","trace[1593598371] 'applied index is now lower than readState.Index' (duration: 215.448504ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T06:21:29.185616Z","caller":"traceutil/trace.go:171","msg":"trace[508850049] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"323.780055ms","start":"2026-08-07T06:21:28.861823Z","end":"2026-08-07T06:21:29.185603Z","steps":["trace[508850049] 'process raft request' (duration: 323.489268ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:29.185627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:28.861777Z","time spent":"323.766985ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T06:21:29.185715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:28.861799Z","time spent":"323.844916ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T06:21:29.185859Z","caller":"traceutil/trace.go:171","msg":"trace[1618084175] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"323.714804ms","start":"2026-08-07T06:21:28.862136Z","end":"2026-08-07T06:21:29.185851Z","steps":["trace[1618084175] 'process raft request' (duration: 323.259513ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:29.185927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:28.86212Z","time spent":"323.759294ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T06:21:29.185952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.006864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:21:29.185938Z","caller":"traceutil/trace.go:171","msg":"trace[1125415030] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"232.826125ms","start":"2026-08-07T06:21:28.95306Z","end":"2026-08-07T06:21:29.185886Z","steps":["trace[1125415030] 'process raft request' (duration: 232.382825ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:21:29.186Z","caller":"traceutil/trace.go:171","msg":"trace[513682643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1099; }","duration":"322.129818ms","start":"2026-08-07T06:21:28.863857Z","end":"2026-08-07T06:21:29.185987Z","steps":["trace[513682643] 'agreement among raft nodes before linearized reading' (duration: 321.936553ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:29.186032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:28.863849Z","time spent":"322.176139ms","remote":"127.0.0.1:44858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-07T06:21:29.186071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.979202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-07T06:21:29.186115Z","caller":"traceutil/trace.go:171","msg":"trace[1479621172] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1099; }","duration":"215.067235ms","start":"2026-08-07T06:21:28.971036Z","end":"2026-08-07T06:21:29.186103Z","steps":["trace[1479621172] 'agreement among raft nodes before linearized reading' (duration: 214.792088ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:21:43.485865Z","caller":"traceutil/trace.go:171","msg":"trace[1768171414] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"136.42364ms","start":"2026-08-07T06:21:43.349419Z","end":"2026-08-07T06:21:43.485842Z","steps":["trace[1768171414] 'process raft request' (duration: 136.387289ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:21:43.486019Z","caller":"traceutil/trace.go:171","msg":"trace[1534566731] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"136.766188ms","start":"2026-08-07T06:21:43.349243Z","end":"2026-08-07T06:21:43.486009Z","steps":["trace[1534566731] 'process raft request' (duration: 136.524742ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:21:43.485896Z","caller":"traceutil/trace.go:171","msg":"trace[1647898491] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"136.641165ms","start":"2026-08-07T06:21:43.349212Z","end":"2026-08-07T06:21:43.485853Z","steps":["trace[1647898491] 'process raft request' (duration: 61.04282ms)","trace[1647898491] 'compare' (duration: 75.429871ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T06:21:45.427881Z","caller":"traceutil/trace.go:171","msg":"trace[1748172295] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"197.331819ms","start":"2026-08-07T06:21:45.230529Z","end":"2026-08-07T06:21:45.427861Z","steps":["trace[1748172295] 'process raft request' (duration: 197.187026ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:21:45.484072Z","caller":"traceutil/trace.go:171","msg":"trace[370880752] linearizableReadLoop","detail":"{readStateIndex:1191; appliedIndex:1189; }","duration":"128.032426ms","start":"2026-08-07T06:21:45.356018Z","end":"2026-08-07T06:21:45.48405Z","steps":["trace[370880752] 'read index received' (duration: 71.769851ms)","trace[370880752] 'applied index is now lower than readState.Index' (duration: 56.261615ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T06:21:45.484144Z","caller":"traceutil/trace.go:171","msg":"trace[1476787418] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"134.23648ms","start":"2026-08-07T06:21:45.349871Z","end":"2026-08-07T06:21:45.484108Z","steps":["trace[1476787418] 'process raft request' (duration: 128.359293ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:45.484284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.26021ms","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-08-07T06:21:45.484316Z","caller":"traceutil/trace.go:171","msg":"trace[1293622515] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1149; }","duration":"128.355772ms","start":"2026-08-07T06:21:45.355953Z","end":"2026-08-07T06:21:45.484309Z","steps":["trace[1293622515] 'agreement among raft nodes before linearized reading' (duration: 128.213339ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:21:46.214021Z","caller":"traceutil/trace.go:171","msg":"trace[1808328001] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"207.490972ms","start":"2026-08-07T06:21:46.006505Z","end":"2026-08-07T06:21:46.213996Z","steps":["trace[1808328001] 'process raft request' (duration: 207.336878ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:21:46.513725Z","caller":"traceutil/trace.go:171","msg":"trace[504189136] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"151.35158ms","start":"2026-08-07T06:21:46.362334Z","end":"2026-08-07T06:21:46.513685Z","steps":["trace[504189136] 'process raft request' (duration: 151.132625ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:21:46.874865Z","caller":"traceutil/trace.go:171","msg":"trace[1084723059] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"120.451584ms","start":"2026-08-07T06:21:46.754388Z","end":"2026-08-07T06:21:46.87484Z","steps":["trace[1084723059] 'process raft request' (duration: 119.948552ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:21:47.652619Z","caller":"traceutil/trace.go:171","msg":"trace[1083335917] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"104.152125ms","start":"2026-08-07T06:21:47.548424Z","end":"2026-08-07T06:21:47.652576Z","steps":["trace[1083335917] 'process raft request' (duration: 49.036518ms)","trace[1083335917] 'compare' (duration: 54.876062ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T06:21:50.1665Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871266323697726,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-07T06:21:50.667521Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871266323697726,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-07T06:21:51.167966Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871266323697726,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-07T06:21:51.515398Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.855037231s","expected-duration":"1s"} {"level":"info","ts":"2026-08-07T06:21:51.515676Z","caller":"traceutil/trace.go:171","msg":"trace[853282691] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"1.8554186s","start":"2026-08-07T06:21:49.660237Z","end":"2026-08-07T06:21:51.515656Z","steps":["trace[853282691] 'process raft request' (duration: 1.855296267s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:51.515868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:49.660211Z","time spent":"1.855576393s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T06:21:51.788825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000041364s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-07T06:21:51.788945Z","caller":"traceutil/trace.go:171","msg":"trace[1651851239] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000199348s","start":"2026-08-07T06:21:49.788722Z","end":"2026-08-07T06:21:51.788922Z","steps":["trace[1651851239] 'agreement among raft nodes before linearized reading' (duration: 2.000073585s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:51.789082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:49.788705Z","time spent":"2.000360653s","remote":"127.0.0.1:44858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/07 06:21:51 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-07T06:21:52.709442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:52.367048Z","time spent":"342.388331ms","remote":"127.0.0.1:51836","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-07T06:21:52.710319Z","caller":"traceutil/trace.go:171","msg":"trace[43531095] linearizableReadLoop","detail":"{readStateIndex:1259; appliedIndex:1257; }","duration":"3.044368755s","start":"2026-08-07T06:21:49.665928Z","end":"2026-08-07T06:21:52.710297Z","steps":["trace[43531095] 'read index received' (duration: 1.849665347s)","trace[43531095] 'applied index is now lower than readState.Index' (duration: 1.194702288s)"],"step_count":2} {"level":"info","ts":"2026-08-07T06:21:52.710422Z","caller":"traceutil/trace.go:171","msg":"trace[571182252] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"1.826883999s","start":"2026-08-07T06:21:50.883475Z","end":"2026-08-07T06:21:52.710359Z","steps":["trace[571182252] 'process raft request' (duration: 1.821253969s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:52.710541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:50.883448Z","time spent":"1.827027022s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T06:21:52.71054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.0445938s","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-08-07T06:21:52.710607Z","caller":"traceutil/trace.go:171","msg":"trace[333758651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1214; }","duration":"3.044703193s","start":"2026-08-07T06:21:49.66589Z","end":"2026-08-07T06:21:52.710593Z","steps":["trace[333758651] 'agreement among raft nodes before linearized reading' (duration: 3.044497128s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:52.710644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:49.665873Z","time spent":"3.044762864s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-07T06:21:52.739644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.191614436s","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-08-07T06:21:52.739689Z","caller":"traceutil/trace.go:171","msg":"trace[1775137677] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1215; }","duration":"1.191691217s","start":"2026-08-07T06:21:51.547988Z","end":"2026-08-07T06:21:52.739679Z","steps":["trace[1775137677] 'agreement among raft nodes before linearized reading' (duration: 1.191554874s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:52.739669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.443842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-07T06:21:52.739716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:51.547973Z","time spent":"1.191737198s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-07T06:21:52.739705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.63371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-07T06:21:52.739744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.071413871s","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-08-07T06:21:52.739763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.835073608s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:21:52.739793Z","caller":"traceutil/trace.go:171","msg":"trace[1529157043] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"1.19048217s","start":"2026-08-07T06:21:51.549297Z","end":"2026-08-07T06:21:52.73978Z","steps":["trace[1529157043] 'process raft request' (duration: 1.190113082s)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:21:52.739798Z","caller":"traceutil/trace.go:171","msg":"trace[1607858434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1215; }","duration":"3.071507393s","start":"2026-08-07T06:21:49.668278Z","end":"2026-08-07T06:21:52.739786Z","steps":["trace[1607858434] 'agreement among raft nodes before linearized reading' (duration: 3.07137373s)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:21:52.739811Z","caller":"traceutil/trace.go:171","msg":"trace[1305679133] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1215; }","duration":"1.835114209s","start":"2026-08-07T06:21:50.904678Z","end":"2026-08-07T06:21:52.739792Z","steps":["trace[1305679133] 'agreement among raft nodes before linearized reading' (duration: 1.835039348s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:52.739719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.04645286s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-07T06:21:52.739845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:50.90464Z","time spent":"1.835196461s","remote":"127.0.0.1:38728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-08-07T06:21:52.739848Z","caller":"traceutil/trace.go:171","msg":"trace[1499767081] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:1215; }","duration":"2.046624104s","start":"2026-08-07T06:21:50.693213Z","end":"2026-08-07T06:21:52.739837Z","steps":["trace[1499767081] 'agreement among raft nodes before linearized reading' (duration: 2.04641407s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:52.739837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:49.668264Z","time spent":"3.071564215s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-07T06:21:52.739874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:51.549286Z","time spent":"1.190546621s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T06:21:52.739886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:50.693194Z","time spent":"2.046680056s","remote":"127.0.0.1:44880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.229\" "} {"level":"info","ts":"2026-08-07T06:21:52.739787Z","caller":"traceutil/trace.go:171","msg":"trace[63921970] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1215; }","duration":"951.767483ms","start":"2026-08-07T06:21:51.788003Z","end":"2026-08-07T06:21:52.73977Z","steps":["trace[63921970] 'agreement among raft nodes before linearized reading' (duration: 951.613729ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:52.739929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:51.787917Z","time spent":"952.002368ms","remote":"127.0.0.1:45162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-08-07T06:21:52.739737Z","caller":"traceutil/trace.go:171","msg":"trace[1860094898] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1215; }","duration":"950.564745ms","start":"2026-08-07T06:21:51.789154Z","end":"2026-08-07T06:21:52.739719Z","steps":["trace[1860094898] 'agreement among raft nodes before linearized reading' (duration: 950.443302ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:52.739961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:51.789128Z","time spent":"950.825901ms","remote":"127.0.0.1:44864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-07T06:21:52.739669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.07225128s","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-08-07T06:21:52.740038Z","caller":"traceutil/trace.go:171","msg":"trace[2021668053] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1215; }","duration":"3.07264713s","start":"2026-08-07T06:21:49.667382Z","end":"2026-08-07T06:21:52.740029Z","steps":["trace[2021668053] 'agreement among raft nodes before linearized reading' (duration: 3.072140868s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:52.74006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:49.667374Z","time spent":"3.072679281s","remote":"127.0.0.1:45116","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":"info","ts":"2026-08-07T06:21:53.872672Z","caller":"traceutil/trace.go:171","msg":"trace[336654509] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"347.709794ms","start":"2026-08-07T06:21:53.524929Z","end":"2026-08-07T06:21:53.872638Z","steps":["trace[336654509] 'process raft request' (duration: 347.429027ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:53.872825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:53.524898Z","time spent":"347.851707ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T06:21:54.892544Z","caller":"traceutil/trace.go:171","msg":"trace[893968494] linearizableReadLoop","detail":"{readStateIndex:1269; appliedIndex:1268; }","duration":"144.915211ms","start":"2026-08-07T06:21:54.747606Z","end":"2026-08-07T06:21:54.892521Z","steps":["trace[893968494] 'read index received' (duration: 144.807259ms)","trace[893968494] 'applied index is now lower than readState.Index' (duration: 106.892µs)"],"step_count":2} {"level":"warn","ts":"2026-08-07T06:21:54.892691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.077555ms","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-08-07T06:21:54.892731Z","caller":"traceutil/trace.go:171","msg":"trace[1028760579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1223; }","duration":"145.158467ms","start":"2026-08-07T06:21:54.747567Z","end":"2026-08-07T06:21:54.892726Z","steps":["trace[1028760579] 'agreement among raft nodes before linearized reading' (duration: 145.029554ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:21:54.892572Z","caller":"traceutil/trace.go:171","msg":"trace[138373723] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"147.89699ms","start":"2026-08-07T06:21:54.744651Z","end":"2026-08-07T06:21:54.892548Z","steps":["trace[138373723] 'process raft request' (duration: 147.727736ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:54.911847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.927039ms","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-08-07T06:21:54.911877Z","caller":"traceutil/trace.go:171","msg":"trace[340053275] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1224; }","duration":"163.00067ms","start":"2026-08-07T06:21:54.74887Z","end":"2026-08-07T06:21:54.911871Z","steps":["trace[340053275] 'agreement among raft nodes before linearized reading' (duration: 162.916768ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:54.911976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.247899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:21:54.912047Z","caller":"traceutil/trace.go:171","msg":"trace[536357166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1224; }","duration":"123.382542ms","start":"2026-08-07T06:21:54.788646Z","end":"2026-08-07T06:21:54.912029Z","steps":["trace[536357166] 'agreement among raft nodes before linearized reading' (duration: 123.219178ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:54.912083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.490299ms","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-08-07T06:21:54.912134Z","caller":"traceutil/trace.go:171","msg":"trace[2125683670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1224; }","duration":"162.57303ms","start":"2026-08-07T06:21:54.749547Z","end":"2026-08-07T06:21:54.91212Z","steps":["trace[2125683670] 'agreement among raft nodes before linearized reading' (duration: 162.387856ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:54.912515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.195359ms","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-08-07T06:21:54.912559Z","caller":"traceutil/trace.go:171","msg":"trace[1214288848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1224; }","duration":"155.24863ms","start":"2026-08-07T06:21:54.7573Z","end":"2026-08-07T06:21:54.912549Z","steps":["trace[1214288848] 'agreement among raft nodes before linearized reading' (duration: 155.142937ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:56.36974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.378937ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-07T06:21:56.369846Z","caller":"traceutil/trace.go:171","msg":"trace[1774940917] linearizableReadLoop","detail":"{readStateIndex:1276; appliedIndex:1275; }","duration":"120.374301ms","start":"2026-08-07T06:21:56.249454Z","end":"2026-08-07T06:21:56.369828Z","steps":["trace[1774940917] 'read index received' (duration: 28.64µs)","trace[1774940917] 'applied index is now lower than readState.Index' (duration: 120.344161ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T06:21:56.36994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.468133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:21:56.370002Z","caller":"traceutil/trace.go:171","msg":"trace[991273473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1229; }","duration":"120.562946ms","start":"2026-08-07T06:21:56.249424Z","end":"2026-08-07T06:21:56.369987Z","steps":["trace[991273473] 'agreement among raft nodes before linearized reading' (duration: 120.454163ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:59.183326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.054438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-07T06:21:59.183408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.609882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-07T06:21:59.183463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.497947ms","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-08-07T06:21:59.183496Z","caller":"traceutil/trace.go:171","msg":"trace[375179914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1236; }","duration":"155.550438ms","start":"2026-08-07T06:21:59.027937Z","end":"2026-08-07T06:21:59.183488Z","steps":["trace[375179914] 'range keys from in-memory index tree' (duration: 155.372944ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:21:59.183485Z","caller":"traceutil/trace.go:171","msg":"trace[126097767] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1236; }","duration":"156.740145ms","start":"2026-08-07T06:21:59.026726Z","end":"2026-08-07T06:21:59.183466Z","steps":["trace[126097767] 'range keys from in-memory index tree' (duration: 156.396698ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:59.183511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.943964ms","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-08-07T06:21:59.183431Z","caller":"traceutil/trace.go:171","msg":"trace[934844098] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1236; }","duration":"212.186591ms","start":"2026-08-07T06:21:58.971225Z","end":"2026-08-07T06:21:59.183412Z","steps":["trace[934844098] 'count revisions from in-memory index tree' (duration: 211.922365ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:21:59.183588Z","caller":"traceutil/trace.go:171","msg":"trace[1911936560] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1236; }","duration":"155.071527ms","start":"2026-08-07T06:21:59.028497Z","end":"2026-08-07T06:21:59.183569Z","steps":["trace[1911936560] 'range keys from in-memory index tree' (duration: 154.77558ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:59.183645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.425409ms","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-08-07T06:21:59.183683Z","caller":"traceutil/trace.go:171","msg":"trace[1299053473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1236; }","duration":"197.514061ms","start":"2026-08-07T06:21:58.986159Z","end":"2026-08-07T06:21:59.183673Z","steps":["trace[1299053473] 'range keys from in-memory index tree' (duration: 197.269535ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:21:59.509636Z","caller":"traceutil/trace.go:171","msg":"trace[961051050] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"322.609562ms","start":"2026-08-07T06:21:59.186998Z","end":"2026-08-07T06:21:59.509608Z","steps":["trace[961051050] 'process raft request' (duration: 322.444428ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:59.509786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:59.186976Z","time spent":"322.730344ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T06:21:59.53388Z","caller":"traceutil/trace.go:171","msg":"trace[1592303111] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"346.790693ms","start":"2026-08-07T06:21:59.187072Z","end":"2026-08-07T06:21:59.533863Z","steps":["trace[1592303111] 'process raft request' (duration: 346.68054ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:59.533985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:59.187051Z","time spent":"346.880425ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T06:21:59.534151Z","caller":"traceutil/trace.go:171","msg":"trace[1142730177] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"345.749259ms","start":"2026-08-07T06:21:59.188358Z","end":"2026-08-07T06:21:59.534107Z","steps":["trace[1142730177] 'process raft request' (duration: 345.477143ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:59.534322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:59.18835Z","time spent":"345.873672ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T06:21:59.535002Z","caller":"traceutil/trace.go:171","msg":"trace[371947512] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"346.602108ms","start":"2026-08-07T06:21:59.18838Z","end":"2026-08-07T06:21:59.534982Z","steps":["trace[371947512] 'process raft request' (duration: 345.669727ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:21:59.535107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:21:59.188369Z","time spent":"346.681461ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T06:22:00.876033Z","caller":"traceutil/trace.go:171","msg":"trace[933106356] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"122.429839ms","start":"2026-08-07T06:22:00.753577Z","end":"2026-08-07T06:22:00.876007Z","steps":["trace[933106356] 'process raft request' (duration: 75.272115ms)","trace[933106356] 'compare' (duration: 46.98352ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T06:22:02.179601Z","caller":"traceutil/trace.go:171","msg":"trace[1159884879] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"584.572648ms","start":"2026-08-07T06:22:01.595004Z","end":"2026-08-07T06:22:02.179576Z","steps":["trace[1159884879] 'process raft request' (duration: 584.446235ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:22:02.179759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:22:01.594986Z","time spent":"584.70322ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T06:22:02.196081Z","caller":"traceutil/trace.go:171","msg":"trace[1132734205] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1292; }","duration":"173.716218ms","start":"2026-08-07T06:22:02.022338Z","end":"2026-08-07T06:22:02.196054Z","steps":["trace[1132734205] 'read index received' (duration: 157.168584ms)","trace[1132734205] 'applied index is now lower than readState.Index' (duration: 16.546744ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T06:22:02.196121Z","caller":"traceutil/trace.go:171","msg":"trace[581728980] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"600.959348ms","start":"2026-08-07T06:22:01.595132Z","end":"2026-08-07T06:22:02.196092Z","steps":["trace[581728980] 'process raft request' (duration: 600.745223ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:22:02.196152Z","caller":"traceutil/trace.go:171","msg":"trace[962920851] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"600.831444ms","start":"2026-08-07T06:22:01.595302Z","end":"2026-08-07T06:22:02.196133Z","steps":["trace[962920851] 'process raft request' (duration: 600.714842ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:22:02.196241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:22:01.59529Z","time spent":"600.905296ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T06:22:02.196284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.928884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-08-07T06:22:02.196291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:22:01.595107Z","time spent":"601.08317ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T06:22:02.196323Z","caller":"traceutil/trace.go:171","msg":"trace[1117125328] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1247; }","duration":"174.023856ms","start":"2026-08-07T06:22:02.022286Z","end":"2026-08-07T06:22:02.19631Z","steps":["trace[1117125328] 'agreement among raft nodes before linearized reading' (duration: 173.875193ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:22:03.18005Z","caller":"traceutil/trace.go:171","msg":"trace[442551582] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"202.396884ms","start":"2026-08-07T06:22:02.977625Z","end":"2026-08-07T06:22:03.180022Z","steps":["trace[442551582] 'process raft request' (duration: 202.23408ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:22:03.976432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.228019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:22:03.976499Z","caller":"traceutil/trace.go:171","msg":"trace[849161717] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1251; }","duration":"139.333672ms","start":"2026-08-07T06:22:03.837147Z","end":"2026-08-07T06:22:03.976481Z","steps":["trace[849161717] 'count revisions from in-memory index tree' (duration: 139.160708ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:22:03.976517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.348459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:22:03.976596Z","caller":"traceutil/trace.go:171","msg":"trace[901252987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1251; }","duration":"188.489292ms","start":"2026-08-07T06:22:03.788087Z","end":"2026-08-07T06:22:03.976576Z","steps":["trace[901252987] 'range keys from in-memory index tree' (duration: 188.270038ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:22:04.700551Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871266323697825,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-07T06:22:04.722329Z","caller":"traceutil/trace.go:171","msg":"trace[1636153194] linearizableReadLoop","detail":"{readStateIndex:1300; appliedIndex:1299; }","duration":"522.013217ms","start":"2026-08-07T06:22:04.200291Z","end":"2026-08-07T06:22:04.722304Z","steps":["trace[1636153194] 'read index received' (duration: 521.760801ms)","trace[1636153194] 'applied index is now lower than readState.Index' (duration: 251.356µs)"],"step_count":2} {"level":"info","ts":"2026-08-07T06:22:04.722407Z","caller":"traceutil/trace.go:171","msg":"trace[933822349] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"535.583351ms","start":"2026-08-07T06:22:04.186799Z","end":"2026-08-07T06:22:04.722383Z","steps":["trace[933822349] 'process raft request' (duration: 535.362606ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:22:04.722517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:22:04.186778Z","time spent":"535.680233ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T06:22:04.722562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.246962ms","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-08-07T06:22:04.722598Z","caller":"traceutil/trace.go:171","msg":"trace[1371970352] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1252; }","duration":"522.324674ms","start":"2026-08-07T06:22:04.200262Z","end":"2026-08-07T06:22:04.722587Z","steps":["trace[1371970352] 'agreement among raft nodes before linearized reading' (duration: 522.174461ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:22:04.722626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.389772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-08-07T06:22:04.722633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:22:04.200248Z","time spent":"522.377445ms","remote":"127.0.0.1:45116","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-08-07T06:22:04.722627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.281923ms","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-08-07T06:22:04.722671Z","caller":"traceutil/trace.go:171","msg":"trace[1194750895] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1252; }","duration":"496.454374ms","start":"2026-08-07T06:22:04.226203Z","end":"2026-08-07T06:22:04.722657Z","steps":["trace[1194750895] 'agreement among raft nodes before linearized reading' (duration: 496.336041ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:22:04.722694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:22:04.226194Z","time spent":"496.495555ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-07T06:22:04.722692Z","caller":"traceutil/trace.go:171","msg":"trace[219736679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1252; }","duration":"522.382315ms","start":"2026-08-07T06:22:04.200292Z","end":"2026-08-07T06:22:04.722674Z","steps":["trace[219736679] 'agreement among raft nodes before linearized reading' (duration: 522.225072ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:22:04.722746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:22:04.200275Z","time spent":"522.455498ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-07T06:22:05.391735Z","caller":"traceutil/trace.go:171","msg":"trace[1526890507] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"665.72068ms","start":"2026-08-07T06:22:04.725989Z","end":"2026-08-07T06:22:05.39171Z","steps":["trace[1526890507] 'process raft request' (duration: 589.899181ms)","trace[1526890507] 'compare' (duration: 75.592933ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T06:22:05.391849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:22:04.725969Z","time spent":"665.820542ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T06:22:06.059282Z","caller":"traceutil/trace.go:171","msg":"trace[1235937085] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"1.333094688s","start":"2026-08-07T06:22:04.726143Z","end":"2026-08-07T06:22:06.059238Z","steps":["trace[1235937085] 'process raft request' (duration: 1.332932094s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:22:06.059422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:22:04.726134Z","time spent":"1.33322282s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T06:22:06.059498Z","caller":"traceutil/trace.go:171","msg":"trace[460363351] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"1.332927724s","start":"2026-08-07T06:22:04.726544Z","end":"2026-08-07T06:22:06.059471Z","steps":["trace[460363351] 'process raft request' (duration: 1.332658648s)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:22:06.059519Z","caller":"traceutil/trace.go:171","msg":"trace[1194931172] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"874.124883ms","start":"2026-08-07T06:22:05.185377Z","end":"2026-08-07T06:22:06.059502Z","steps":["trace[1194931172] 'process raft request' (duration: 874.0177ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:22:06.060291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:22:05.185274Z","time spent":"874.942951ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T06:22:06.059598Z","caller":"traceutil/trace.go:171","msg":"trace[1546573956] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1301; }","duration":"475.144058ms","start":"2026-08-07T06:22:05.584437Z","end":"2026-08-07T06:22:06.059581Z","steps":["trace[1546573956] 'read index received' (duration: 474.619086ms)","trace[1546573956] 'applied index is now lower than readState.Index' (duration: 524.222µs)"],"step_count":2} {"level":"warn","ts":"2026-08-07T06:22:06.05964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:22:04.726533Z","time spent":"1.333030006s","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T06:22:06.059673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.230871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-07T06:22:06.060606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.195568ms","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-08-07T06:22:06.06068Z","caller":"traceutil/trace.go:171","msg":"trace[561788510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1257; }","duration":"430.29316ms","start":"2026-08-07T06:22:05.630377Z","end":"2026-08-07T06:22:06.06067Z","steps":["trace[561788510] 'agreement among raft nodes before linearized reading' (duration: 430.146336ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:22:06.060705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:22:05.630363Z","time spent":"430.337951ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-07T06:22:06.060715Z","caller":"traceutil/trace.go:171","msg":"trace[218816041] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1257; }","duration":"476.244705ms","start":"2026-08-07T06:22:05.584421Z","end":"2026-08-07T06:22:06.060666Z","steps":["trace[218816041] 'agreement among raft nodes before linearized reading' (duration: 475.211041ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:22:06.060787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.143592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:22:06.060805Z","caller":"traceutil/trace.go:171","msg":"trace[675017610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1257; }","duration":"272.183483ms","start":"2026-08-07T06:22:05.788617Z","end":"2026-08-07T06:22:06.0608Z","steps":["trace[675017610] 'agreement among raft nodes before linearized reading' (duration: 272.155652ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:22:06.06079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:22:05.584369Z","time spent":"476.400558ms","remote":"127.0.0.1:38628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-08-07T06:22:06.059688Z","caller":"traceutil/trace.go:171","msg":"trace[1490387199] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"790.315469ms","start":"2026-08-07T06:22:05.26936Z","end":"2026-08-07T06:22:06.059676Z","steps":["trace[1490387199] 'process raft request' (duration: 790.110014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:22:06.060998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:22:05.269336Z","time spent":"791.62705ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T06:22:07.609457Z","caller":"traceutil/trace.go:171","msg":"trace[1894677801] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"211.274479ms","start":"2026-08-07T06:22:07.39816Z","end":"2026-08-07T06:22:07.609434Z","steps":["trace[1894677801] 'process raft request' (duration: 211.150696ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:22:08.015499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.954063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:22:08.015548Z","caller":"traceutil/trace.go:171","msg":"trace[2035664307] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1260; }","duration":"227.034575ms","start":"2026-08-07T06:22:07.788502Z","end":"2026-08-07T06:22:08.015537Z","steps":["trace[2035664307] 'range keys from in-memory index tree' (duration: 226.904832ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:22:08.015546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.406145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:22:08.015608Z","caller":"traceutil/trace.go:171","msg":"trace[2017988901] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1260; }","duration":"212.491087ms","start":"2026-08-07T06:22:07.803103Z","end":"2026-08-07T06:22:08.015595Z","steps":["trace[2017988901] 'count revisions from in-memory index tree' (duration: 212.372334ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:22:08.91232Z","caller":"traceutil/trace.go:171","msg":"trace[1208945424] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1313; }","duration":"124.721472ms","start":"2026-08-07T06:22:08.787578Z","end":"2026-08-07T06:22:08.912299Z","steps":["trace[1208945424] 'read index received' (duration: 124.527368ms)","trace[1208945424] 'applied index is now lower than readState.Index' (duration: 193.184µs)"],"step_count":2} {"level":"warn","ts":"2026-08-07T06:22:08.912459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.858676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T06:22:08.912497Z","caller":"traceutil/trace.go:171","msg":"trace[424919334] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1265; }","duration":"124.937677ms","start":"2026-08-07T06:22:08.787549Z","end":"2026-08-07T06:22:08.912487Z","steps":["trace[424919334] 'agreement among raft nodes before linearized reading' (duration: 124.835205ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:22:08.912578Z","caller":"traceutil/trace.go:171","msg":"trace[2107015061] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"145.917915ms","start":"2026-08-07T06:22:08.766634Z","end":"2026-08-07T06:22:08.912551Z","steps":["trace[2107015061] 'process raft request' (duration: 145.536245ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:22:11.108106Z","caller":"traceutil/trace.go:171","msg":"trace[1091118754] linearizableReadLoop","detail":"{readStateIndex:1320; appliedIndex:1319; }","duration":"319.648664ms","start":"2026-08-07T06:22:10.788435Z","end":"2026-08-07T06:22:11.108084Z","steps":["trace[1091118754] 'read index received' (duration: 319.542631ms)","trace[1091118754] 'applied index is now lower than readState.Index' (duration: 104.772µs)"],"step_count":2} {"level":"warn","ts":"2026-08-07T06:22:11.108126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:22:10.695785Z","time spent":"412.334552ms","remote":"127.0.0.1:44880","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-07T06:22:11.108261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.813427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-07T06:22:11.108305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.245235ms","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-08-07T06:22:11.108339Z","caller":"traceutil/trace.go:171","msg":"trace[1738029341] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1270; }","duration":"191.282286ms","start":"2026-08-07T06:22:10.917047Z","end":"2026-08-07T06:22:11.108329Z","steps":["trace[1738029341] 'agreement among raft nodes before linearized reading' (duration: 191.162713ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:22:11.108341Z","caller":"traceutil/trace.go:171","msg":"trace[1700561414] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1270; }","duration":"319.94475ms","start":"2026-08-07T06:22:10.788382Z","end":"2026-08-07T06:22:11.108327Z","steps":["trace[1700561414] 'agreement among raft nodes before linearized reading' (duration: 319.809707ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:22:11.108417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:22:10.788361Z","time spent":"320.046762ms","remote":"127.0.0.1:44864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-07T06:22:12.412806Z","caller":"traceutil/trace.go:171","msg":"trace[1974158842] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"304.471021ms","start":"2026-08-07T06:22:12.108314Z","end":"2026-08-07T06:22:12.412785Z","steps":["trace[1974158842] 'process raft request' (duration: 303.702834ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:22:12.412905Z","caller":"traceutil/trace.go:171","msg":"trace[1739553006] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1324; }","duration":"301.239106ms","start":"2026-08-07T06:22:12.111657Z","end":"2026-08-07T06:22:12.412896Z","steps":["trace[1739553006] 'read index received' (duration: 300.372806ms)","trace[1739553006] 'applied index is now lower than readState.Index' (duration: 865.3µs)"],"step_count":2} {"level":"warn","ts":"2026-08-07T06:22:12.41303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.378219ms","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-08-07T06:22:12.41305Z","caller":"traceutil/trace.go:171","msg":"trace[805261916] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1275; }","duration":"301.44017ms","start":"2026-08-07T06:22:12.111606Z","end":"2026-08-07T06:22:12.413046Z","steps":["trace[805261916] 'agreement among raft nodes before linearized reading' (duration: 301.342778ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:22:12.413065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:22:12.111586Z","time spent":"301.474901ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-07T06:22:12.413198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.134304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-08-07T06:22:12.41321Z","caller":"traceutil/trace.go:171","msg":"trace[429923679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1275; }","duration":"298.168965ms","start":"2026-08-07T06:22:12.115038Z","end":"2026-08-07T06:22:12.413207Z","steps":["trace[429923679] 'agreement among raft nodes before linearized reading' (duration: 298.130624ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:22:12.413329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.99883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-07T06:22:12.413342Z","caller":"traceutil/trace.go:171","msg":"trace[84771037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1275; }","duration":"285.043561ms","start":"2026-08-07T06:22:12.128294Z","end":"2026-08-07T06:22:12.413338Z","steps":["trace[84771037] 'agreement among raft nodes before linearized reading' (duration: 284.99838ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:22:12.413484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:22:12.108281Z","time spent":"304.586444ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T06:22:14.89057Z","caller":"traceutil/trace.go:171","msg":"trace[955609569] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"136.304572ms","start":"2026-08-07T06:22:14.754239Z","end":"2026-08-07T06:22:14.890544Z","steps":["trace[955609569] 'process raft request' (duration: 136.181998ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:22:19.727104Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871266323697994,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-07T06:22:19.816705Z","caller":"traceutil/trace.go:171","msg":"trace[543405791] linearizableReadLoop","detail":"{readStateIndex:1383; appliedIndex:1382; }","duration":"589.608924ms","start":"2026-08-07T06:22:19.22706Z","end":"2026-08-07T06:22:19.816669Z","steps":["trace[543405791] 'read index received' (duration: 589.239856ms)","trace[543405791] 'applied index is now lower than readState.Index' (duration: 367.528µs)"],"step_count":2} {"level":"info","ts":"2026-08-07T06:22:19.816776Z","caller":"traceutil/trace.go:171","msg":"trace[290497010] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"625.962097ms","start":"2026-08-07T06:22:19.190775Z","end":"2026-08-07T06:22:19.816737Z","steps":["trace[290497010] 'process raft request' (duration: 625.633179ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:22:19.816953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:22:19.190747Z","time spent":"626.10561ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T06:22:19.817035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.90792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-07T06:22:19.817285Z","caller":"traceutil/trace.go:171","msg":"trace[2047186700] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1332; }","duration":"590.129277ms","start":"2026-08-07T06:22:19.227009Z","end":"2026-08-07T06:22:19.817139Z","steps":["trace[2047186700] 'agreement among raft nodes before linearized reading' (duration: 589.81935ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:22:19.817347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:22:19.226988Z","time spent":"590.344821ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":55,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-08-07T06:22:20.477808Z","caller":"traceutil/trace.go:171","msg":"trace[106851184] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"656.232459ms","start":"2026-08-07T06:22:19.821547Z","end":"2026-08-07T06:22:20.477779Z","steps":["trace[106851184] 'process raft request' (duration: 649.869952ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:22:20.477926Z","caller":"traceutil/trace.go:171","msg":"trace[199931451] linearizableReadLoop","detail":"{readStateIndex:1384; appliedIndex:1383; }","duration":"189.373332ms","start":"2026-08-07T06:22:20.288532Z","end":"2026-08-07T06:22:20.477906Z","steps":["trace[199931451] 'read index received' (duration: 182.845301ms)","trace[199931451] 'applied index is now lower than readState.Index' (duration: 6.527091ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T06:22:20.47798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:22:19.821526Z","time spent":"656.375912ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T06:22:20.478029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.474694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-07T06:22:20.478072Z","caller":"traceutil/trace.go:171","msg":"trace[293884485] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1334; }","duration":"189.591947ms","start":"2026-08-07T06:22:20.288471Z","end":"2026-08-07T06:22:20.478063Z","steps":["trace[293884485] 'agreement among raft nodes before linearized reading' (duration: 189.508045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:22:23.624183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.59036ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-07T06:22:23.62428Z","caller":"traceutil/trace.go:171","msg":"trace[1535627687] linearizableReadLoop","detail":"{readStateIndex:1419; appliedIndex:1418; }","duration":"444.618362ms","start":"2026-08-07T06:22:23.179646Z","end":"2026-08-07T06:22:23.624264Z","steps":["trace[1535627687] 'read index received' (duration: 60.071µs)","trace[1535627687] 'applied index is now lower than readState.Index' (duration: 444.557111ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T06:22:23.624471Z","caller":"traceutil/trace.go:171","msg":"trace[1461427966] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"651.997531ms","start":"2026-08-07T06:22:22.972461Z","end":"2026-08-07T06:22:23.624458Z","steps":["trace[1461427966] 'process raft request' (duration: 160.060953ms)","trace[1461427966] 'compare' (duration: 491.477918ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T06:22:23.62454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:22:22.972417Z","time spent":"652.090944ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T06:22:23.624624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.957369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-sh65d\" ","response":"range_response_count:1 size:12642"} {"level":"info","ts":"2026-08-07T06:22:23.624704Z","caller":"traceutil/trace.go:171","msg":"trace[288161920] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-sh65d; range_end:; response_count:1; response_revision:1367; }","duration":"445.089973ms","start":"2026-08-07T06:22:23.179595Z","end":"2026-08-07T06:22:23.624685Z","steps":["trace[288161920] 'agreement among raft nodes before linearized reading' (duration: 444.851567ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:22:23.624755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:22:23.179571Z","time spent":"445.174795ms","remote":"127.0.0.1:45020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":12664,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-sh65d\" "} {"level":"info","ts":"2026-08-07T06:22:23.627063Z","caller":"traceutil/trace.go:171","msg":"trace[1918715337] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"318.366253ms","start":"2026-08-07T06:22:23.308667Z","end":"2026-08-07T06:22:23.627034Z","steps":["trace[1918715337] 'process raft request' (duration: 318.139608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:22:23.627407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:22:23.308629Z","time spent":"318.66567ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T06:22:23.781257Z","caller":"traceutil/trace.go:171","msg":"trace[2094872090] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"104.163625ms","start":"2026-08-07T06:22:23.677068Z","end":"2026-08-07T06:22:23.781231Z","steps":["trace[2094872090] 'process raft request' (duration: 104.095194ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:22:23.78138Z","caller":"traceutil/trace.go:171","msg":"trace[1572226169] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"108.313791ms","start":"2026-08-07T06:22:23.673011Z","end":"2026-08-07T06:22:23.781325Z","steps":["trace[1572226169] 'process raft request' (duration: 108.018045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:24:45.566563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.023005ms","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-08-07T06:24:45.566611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.164515ms","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-08-07T06:24:45.566765Z","caller":"traceutil/trace.go:171","msg":"trace[732237199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2022; }","duration":"139.23028ms","start":"2026-08-07T06:24:45.427453Z","end":"2026-08-07T06:24:45.566683Z","steps":["trace[732237199] 'range keys from in-memory index tree' (duration: 138.792019ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:24:45.56688Z","caller":"traceutil/trace.go:171","msg":"trace[952736064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2022; }","duration":"135.471482ms","start":"2026-08-07T06:24:45.431384Z","end":"2026-08-07T06:24:45.566855Z","steps":["trace[952736064] 'range keys from in-memory index tree' (duration: 134.841178ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:25:16.109614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.258641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-08-07T06:25:16.109682Z","caller":"traceutil/trace.go:171","msg":"trace[2060638792] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2183; }","duration":"177.365974ms","start":"2026-08-07T06:25:15.932301Z","end":"2026-08-07T06:25:16.109667Z","steps":["trace[2060638792] 'range keys from in-memory index tree' (duration: 177.117978ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:25:16.109834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.338204ms","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-08-07T06:25:16.110023Z","caller":"traceutil/trace.go:171","msg":"trace[1145432381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2183; }","duration":"276.534759ms","start":"2026-08-07T06:25:15.833426Z","end":"2026-08-07T06:25:16.109961Z","steps":["trace[1145432381] 'range keys from in-memory index tree' (duration: 275.848713ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:25:16.251198Z","caller":"traceutil/trace.go:171","msg":"trace[1156361079] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"137.418689ms","start":"2026-08-07T06:25:16.113754Z","end":"2026-08-07T06:25:16.251172Z","steps":["trace[1156361079] 'process raft request' (duration: 136.957009ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:25:16.25242Z","caller":"traceutil/trace.go:171","msg":"trace[976975275] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"138.437423ms","start":"2026-08-07T06:25:16.113951Z","end":"2026-08-07T06:25:16.252389Z","steps":["trace[976975275] 'process raft request' (duration: 138.278589ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:25:21.722542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.655711ms","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-08-07T06:25:21.722601Z","caller":"traceutil/trace.go:171","msg":"trace[1636400229] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2215; }","duration":"241.768385ms","start":"2026-08-07T06:25:21.48082Z","end":"2026-08-07T06:25:21.722589Z","steps":["trace[1636400229] 'range keys from in-memory index tree' (duration: 241.477497ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:25:21.722854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.609598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-08-07T06:25:21.722948Z","caller":"traceutil/trace.go:171","msg":"trace[912452568] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2215; }","duration":"333.753913ms","start":"2026-08-07T06:25:21.389175Z","end":"2026-08-07T06:25:21.722929Z","steps":["trace[912452568] 'range keys from in-memory index tree' (duration: 333.413334ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:25:21.722842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.753044ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-07T06:25:21.723064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.036087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-07T06:25:21.723094Z","caller":"traceutil/trace.go:171","msg":"trace[906229511] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:2215; }","duration":"174.092137ms","start":"2026-08-07T06:25:21.548993Z","end":"2026-08-07T06:25:21.723085Z","steps":["trace[906229511] 'count revisions from in-memory index tree' (duration: 173.953954ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:25:21.723083Z","caller":"traceutil/trace.go:171","msg":"trace[694206829] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2215; }","duration":"229.01233ms","start":"2026-08-07T06:25:21.494043Z","end":"2026-08-07T06:25:21.723056Z","steps":["trace[694206829] 'range keys from in-memory index tree' (duration: 228.683521ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:25:21.722997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:25:21.389154Z","time spent":"333.833275ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-08-07T06:25:21.723585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.050083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-07T06:25:21.723689Z","caller":"traceutil/trace.go:171","msg":"trace[240506658] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2215; }","duration":"214.188226ms","start":"2026-08-07T06:25:21.509475Z","end":"2026-08-07T06:25:21.723663Z","steps":["trace[240506658] 'range keys from in-memory index tree' (duration: 213.46526ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:25:21.929396Z","caller":"traceutil/trace.go:171","msg":"trace[189555537] linearizableReadLoop","detail":"{readStateIndex:2307; appliedIndex:2305; }","duration":"153.798939ms","start":"2026-08-07T06:25:21.775576Z","end":"2026-08-07T06:25:21.929375Z","steps":["trace[189555537] 'read index received' (duration: 73.628334ms)","trace[189555537] 'applied index is now lower than readState.Index' (duration: 80.169545ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T06:25:21.929532Z","caller":"traceutil/trace.go:171","msg":"trace[1101203686] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"202.037074ms","start":"2026-08-07T06:25:21.727462Z","end":"2026-08-07T06:25:21.929499Z","steps":["trace[1101203686] 'process raft request' (duration: 121.802869ms)","trace[1101203686] 'compare' (duration: 79.930188ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T06:25:21.929627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.031334ms","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-08-07T06:25:21.929697Z","caller":"traceutil/trace.go:171","msg":"trace[807163459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2218; }","duration":"154.147327ms","start":"2026-08-07T06:25:21.775535Z","end":"2026-08-07T06:25:21.929683Z","steps":["trace[807163459] 'agreement among raft nodes before linearized reading' (duration: 153.932042ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:25:21.929788Z","caller":"traceutil/trace.go:171","msg":"trace[2132436763] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"200.11878ms","start":"2026-08-07T06:25:21.729662Z","end":"2026-08-07T06:25:21.929781Z","steps":["trace[2132436763] 'process raft request' (duration: 199.645229ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T06:25:22.627648Z","caller":"traceutil/trace.go:171","msg":"trace[1086635829] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"353.202032ms","start":"2026-08-07T06:25:22.274421Z","end":"2026-08-07T06:25:22.627623Z","steps":["trace[1086635829] 'process raft request' (duration: 353.052688ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:25:22.6278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:25:22.274408Z","time spent":"353.311375ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T06:25:22.64149Z","caller":"traceutil/trace.go:171","msg":"trace[1217416431] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"365.614889ms","start":"2026-08-07T06:25:22.275851Z","end":"2026-08-07T06:25:22.641466Z","steps":["trace[1217416431] 'process raft request' (duration: 365.491285ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:25:22.641645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:25:22.27584Z","time spent":"365.716772ms","remote":"127.0.0.1:45116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T06:25:38.735461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.641169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:pod-garbage-collector\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-08-07T06:25:38.735562Z","caller":"traceutil/trace.go:171","msg":"trace[504178635] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:pod-garbage-collector; range_end:; response_count:1; response_revision:2300; }","duration":"361.788841ms","start":"2026-08-07T06:25:38.373751Z","end":"2026-08-07T06:25:38.73554Z","steps":["trace[504178635] 'range keys from in-memory index tree' (duration: 361.542196ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:25:38.735614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:25:38.373735Z","time spent":"361.868604ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":781,"request content":"key:\"/registry/clusterrolebindings/system:controller:pod-garbage-collector\" "} {"level":"warn","ts":"2026-08-07T06:25:38.735655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.482926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:persistent-volume-binder\" ","response":"range_response_count:1 size:771"} {"level":"info","ts":"2026-08-07T06:25:38.735775Z","caller":"traceutil/trace.go:171","msg":"trace[2049374462] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:persistent-volume-binder; range_end:; response_count:1; response_revision:2300; }","duration":"349.63966ms","start":"2026-08-07T06:25:38.3861Z","end":"2026-08-07T06:25:38.73574Z","steps":["trace[2049374462] 'range keys from in-memory index tree' (duration: 349.372143ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:25:38.735818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T06:25:38.38608Z","time spent":"349.728023ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":793,"request content":"key:\"/registry/clusterrolebindings/system:controller:persistent-volume-binder\" "} {"level":"info","ts":"2026-08-07T06:25:59.819666Z","caller":"traceutil/trace.go:171","msg":"trace[1233636529] linearizableReadLoop","detail":"{readStateIndex:2493; appliedIndex:2492; }","duration":"179.459262ms","start":"2026-08-07T06:25:59.64019Z","end":"2026-08-07T06:25:59.819649Z","steps":["trace[1233636529] 'read index received' (duration: 179.37798ms)","trace[1233636529] 'applied index is now lower than readState.Index' (duration: 80.652µs)"],"step_count":2} {"level":"info","ts":"2026-08-07T06:25:59.819738Z","caller":"traceutil/trace.go:171","msg":"trace[1577034264] transaction","detail":"{read_only:false; response_revision:2397; number_of_response:1; }","duration":"220.88038ms","start":"2026-08-07T06:25:59.598822Z","end":"2026-08-07T06:25:59.819702Z","steps":["trace[1577034264] 'process raft request' (duration: 220.730567ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T06:25:59.81987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.665726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-sh65d\" ","response":"range_response_count:1 size:13724"} {"level":"info","ts":"2026-08-07T06:25:59.819902Z","caller":"traceutil/trace.go:171","msg":"trace[1745247120] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-sh65d; range_end:; response_count:1; response_revision:2397; }","duration":"179.741048ms","start":"2026-08-07T06:25:59.640152Z","end":"2026-08-07T06:25:59.819893Z","steps":["trace[1745247120] 'agreement among raft nodes before linearized reading' (duration: 179.586885ms)"],"step_count":1}