{"level":"warn","ts":"2026-05-29T21:59:57.137988Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T21:59:57.138065Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216: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-05-29T21:59:57.138129Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T21:59:57.138142Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-05-29T21:59:57.138157Z","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-05-29T21:59:57.138756Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-05-29T21:59:57.138831Z","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.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216: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-05-29T21:59:57.144275Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.256825ms"} {"level":"info","ts":"2026-05-29T21:59:57.151915Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-05-29T21:59:57.151998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T21:59:57.152021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-05-29T21:59:57.152035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-29T21:59:57.152043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-05-29T21:59:57.152083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-05-29T21:59:57.163854Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T21:59:57.166311Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T21:59:57.167687Z","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-05-29T21:59:57.171538Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-29T21:59:57.171731Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-29T21:59:57.172232Z","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-05-29T21:59:57.172291Z","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-05-29T21:59:57.172333Z","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-05-29T21:59:57.174276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-05-29T21:59:57.174681Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-05-29T21:59:57.177229Z","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-05-29T21:59:57.177385Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-05-29T21:59:57.177416Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-05-29T21:59:57.177939Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-29T21:59:57.177964Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T21:59:57.35264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T21:59:57.352691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T21:59:57.352738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-05-29T21:59:57.35277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-05-29T21:59:57.352791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-05-29T21:59:57.352827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-05-29T21:59:57.35285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-05-29T21:59:57.449837Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:59:57.461791Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-29T21:59:57.461808Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T21:59:57.461842Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T21:59:57.462122Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T21:59:57.462195Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T21:59:57.462658Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:59:57.462903Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:59:57.462949Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:59:57.464178Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-05-29T21:59:57.475319Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-29T22:00:01.906905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.195283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-29T22:00:01.907057Z","caller":"traceutil/trace.go:171","msg":"trace[347432222] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:281; }","duration":"132.395477ms","start":"2026-05-29T22:00:01.774621Z","end":"2026-05-29T22:00:01.907016Z","steps":["trace[347432222] 'range keys from in-memory index tree' (duration: 132.030389ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:02.098781Z","caller":"traceutil/trace.go:171","msg":"trace[1413180618] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"136.120188ms","start":"2026-05-29T22:00:01.962627Z","end":"2026-05-29T22:00:02.098747Z","steps":["trace[1413180618] 'process raft request' (duration: 67.177154ms)","trace[1413180618] 'compare' (duration: 68.790121ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:00:02.098936Z","caller":"traceutil/trace.go:171","msg":"trace[1445168111] linearizableReadLoop","detail":"{readStateIndex:289; appliedIndex:288; }","duration":"125.180807ms","start":"2026-05-29T22:00:01.973734Z","end":"2026-05-29T22:00:02.098915Z","steps":["trace[1445168111] 'read index received' (duration: 56.07894ms)","trace[1445168111] 'applied index is now lower than readState.Index' (duration: 69.101227ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:00:02.099097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.35182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-29T22:00:02.099122Z","caller":"traceutil/trace.go:171","msg":"trace[1397752] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:284; }","duration":"125.416882ms","start":"2026-05-29T22:00:01.973698Z","end":"2026-05-29T22:00:02.099114Z","steps":["trace[1397752] 'agreement among raft nodes before linearized reading' (duration: 125.33546ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:02.327332Z","caller":"traceutil/trace.go:171","msg":"trace[1187697551] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"225.147267ms","start":"2026-05-29T22:00:02.102158Z","end":"2026-05-29T22:00:02.327306Z","steps":["trace[1187697551] 'process raft request' (duration: 225.021994ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:02.331859Z","caller":"traceutil/trace.go:171","msg":"trace[1814836968] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"228.478549ms","start":"2026-05-29T22:00:02.103351Z","end":"2026-05-29T22:00:02.33183Z","steps":["trace[1814836968] 'process raft request' (duration: 228.328726ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:02.332147Z","caller":"traceutil/trace.go:171","msg":"trace[1328177527] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"201.320418ms","start":"2026-05-29T22:00:02.130766Z","end":"2026-05-29T22:00:02.332086Z","steps":["trace[1328177527] 'process raft request' (duration: 201.076323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:02.332178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.028959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-05-29T22:00:02.332192Z","caller":"traceutil/trace.go:171","msg":"trace[129598336] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"201.37386ms","start":"2026-05-29T22:00:02.130741Z","end":"2026-05-29T22:00:02.332114Z","steps":["trace[129598336] 'process raft request' (duration: 201.063003ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:02.332224Z","caller":"traceutil/trace.go:171","msg":"trace[820049323] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:290; }","duration":"228.153202ms","start":"2026-05-29T22:00:02.104058Z","end":"2026-05-29T22:00:02.332211Z","steps":["trace[820049323] 'agreement among raft nodes before linearized reading' (duration: 227.929897ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:02.331954Z","caller":"traceutil/trace.go:171","msg":"trace[1024969458] linearizableReadLoop","detail":"{readStateIndex:292; appliedIndex:290; }","duration":"227.868976ms","start":"2026-05-29T22:00:02.104069Z","end":"2026-05-29T22:00:02.331938Z","steps":["trace[1024969458] 'read index received' (duration: 223.215464ms)","trace[1024969458] 'applied index is now lower than readState.Index' (duration: 4.652682ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:00:02.332015Z","caller":"traceutil/trace.go:171","msg":"trace[682297466] transaction","detail":"{read_only:false; number_of_response:0; response_revision:286; }","duration":"201.33526ms","start":"2026-05-29T22:00:02.130666Z","end":"2026-05-29T22:00:02.332002Z","steps":["trace[682297466] 'process raft request' (duration: 201.113755ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:02.332068Z","caller":"traceutil/trace.go:171","msg":"trace[1403379935] transaction","detail":"{read_only:false; number_of_response:0; response_revision:286; }","duration":"224.723776ms","start":"2026-05-29T22:00:02.107328Z","end":"2026-05-29T22:00:02.332052Z","steps":["trace[1403379935] 'process raft request' (duration: 224.415409ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:02.332117Z","caller":"traceutil/trace.go:171","msg":"trace[809642402] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"201.195915ms","start":"2026-05-29T22:00:02.130905Z","end":"2026-05-29T22:00:02.332101Z","steps":["trace[809642402] 'process raft request' (duration: 200.96525ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:02.332126Z","caller":"traceutil/trace.go:171","msg":"trace[1531193781] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"201.188116ms","start":"2026-05-29T22:00:02.130928Z","end":"2026-05-29T22:00:02.332116Z","steps":["trace[1531193781] 'process raft request' (duration: 200.977431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:02.333194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.462629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:00:02.334012Z","caller":"traceutil/trace.go:171","msg":"trace[1918344549] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:0; response_revision:291; }","duration":"218.55087ms","start":"2026-05-29T22:00:02.114685Z","end":"2026-05-29T22:00:02.333236Z","steps":["trace[1918344549] 'agreement among raft nodes before linearized reading' (duration: 218.438197ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:30.638442Z","caller":"traceutil/trace.go:171","msg":"trace[860778332] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:499; }","duration":"113.282585ms","start":"2026-05-29T22:00:30.52514Z","end":"2026-05-29T22:00:30.638423Z","steps":["trace[860778332] 'read index received' (duration: 29.439541ms)","trace[860778332] 'applied index is now lower than readState.Index' (duration: 83.842344ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:00:30.638564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.416757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:00:30.638593Z","caller":"traceutil/trace.go:171","msg":"trace[800215510] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:484; }","duration":"113.506729ms","start":"2026-05-29T22:00:30.525078Z","end":"2026-05-29T22:00:30.638584Z","steps":["trace[800215510] 'agreement among raft nodes before linearized reading' (duration: 113.426347ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:30.638765Z","caller":"traceutil/trace.go:171","msg":"trace[413632016] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"131.308352ms","start":"2026-05-29T22:00:30.507418Z","end":"2026-05-29T22:00:30.638726Z","steps":["trace[413632016] 'process raft request' (duration: 47.096751ms)","trace[413632016] 'compare' (duration: 83.814963ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:00:31.376774Z","caller":"traceutil/trace.go:171","msg":"trace[1076242224] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"130.115408ms","start":"2026-05-29T22:00:31.245697Z","end":"2026-05-29T22:00:31.375813Z","steps":["trace[1076242224] 'process raft request' (duration: 126.705639ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:33.413485Z","caller":"traceutil/trace.go:171","msg":"trace[1410904373] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"164.724808ms","start":"2026-05-29T22:00:33.248727Z","end":"2026-05-29T22:00:33.413452Z","steps":["trace[1410904373] 'process raft request' (duration: 160.01641ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:33.414061Z","caller":"traceutil/trace.go:171","msg":"trace[4599332] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"164.839373ms","start":"2026-05-29T22:00:33.249191Z","end":"2026-05-29T22:00:33.414031Z","steps":["trace[4599332] 'process raft request' (duration: 164.197727ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:33.415439Z","caller":"traceutil/trace.go:171","msg":"trace[1372483166] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"166.159892ms","start":"2026-05-29T22:00:33.249262Z","end":"2026-05-29T22:00:33.415422Z","steps":["trace[1372483166] 'process raft request' (duration: 164.72422ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:33.481737Z","caller":"traceutil/trace.go:171","msg":"trace[537124789] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"218.583304ms","start":"2026-05-29T22:00:33.263122Z","end":"2026-05-29T22:00:33.481706Z","steps":["trace[537124789] 'process raft request' (duration: 218.37935ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:33.481798Z","caller":"traceutil/trace.go:171","msg":"trace[698130642] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"217.92697ms","start":"2026-05-29T22:00:33.263838Z","end":"2026-05-29T22:00:33.481765Z","steps":["trace[698130642] 'process raft request' (duration: 217.780386ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:33.48195Z","caller":"traceutil/trace.go:171","msg":"trace[1648990192] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"213.580109ms","start":"2026-05-29T22:00:33.26836Z","end":"2026-05-29T22:00:33.481941Z","steps":["trace[1648990192] 'process raft request' (duration: 213.338193ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:33.481894Z","caller":"traceutil/trace.go:171","msg":"trace[377066017] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"217.265435ms","start":"2026-05-29T22:00:33.264597Z","end":"2026-05-29T22:00:33.481862Z","steps":["trace[377066017] 'process raft request' (duration: 217.06008ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:33.48211Z","caller":"traceutil/trace.go:171","msg":"trace[777492507] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"203.582168ms","start":"2026-05-29T22:00:33.278509Z","end":"2026-05-29T22:00:33.482092Z","steps":["trace[777492507] 'process raft request' (duration: 203.352783ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:33.482185Z","caller":"traceutil/trace.go:171","msg":"trace[1340730134] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"191.361505ms","start":"2026-05-29T22:00:33.290803Z","end":"2026-05-29T22:00:33.482165Z","steps":["trace[1340730134] 'process raft request' (duration: 191.201971ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:33.482283Z","caller":"traceutil/trace.go:171","msg":"trace[1073455831] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"183.76529ms","start":"2026-05-29T22:00:33.298506Z","end":"2026-05-29T22:00:33.482272Z","steps":["trace[1073455831] 'process raft request' (duration: 183.585466ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:33.48233Z","caller":"traceutil/trace.go:171","msg":"trace[347415005] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"174.640698ms","start":"2026-05-29T22:00:33.307681Z","end":"2026-05-29T22:00:33.482322Z","steps":["trace[347415005] 'process raft request' (duration: 174.479375ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:33.486258Z","caller":"traceutil/trace.go:171","msg":"trace[276261321] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"210.954748ms","start":"2026-05-29T22:00:33.270994Z","end":"2026-05-29T22:00:33.481949Z","steps":["trace[276261321] 'process raft request' (duration: 210.823415ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:33.953124Z","caller":"traceutil/trace.go:171","msg":"trace[2099627909] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"262.950241ms","start":"2026-05-29T22:00:33.690147Z","end":"2026-05-29T22:00:33.953097Z","steps":["trace[2099627909] 'process raft request' (duration: 258.297753ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:33.953466Z","caller":"traceutil/trace.go:171","msg":"trace[279718827] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"231.453772ms","start":"2026-05-29T22:00:33.721967Z","end":"2026-05-29T22:00:33.953421Z","steps":["trace[279718827] 'process raft request' (duration: 231.393381ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:33.953496Z","caller":"traceutil/trace.go:171","msg":"trace[561958595] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"261.309523ms","start":"2026-05-29T22:00:33.692151Z","end":"2026-05-29T22:00:33.953461Z","steps":["trace[561958595] 'process raft request' (duration: 261.036887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:35.374626Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.19426041s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T22:00:35.375505Z","caller":"traceutil/trace.go:171","msg":"trace[500623414] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"1.198064187s","start":"2026-05-29T22:00:34.177415Z","end":"2026-05-29T22:00:35.37548Z","steps":["trace[500623414] 'process raft request' (duration: 1.197815732s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:35.376195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:34.17739Z","time spent":"1.198186559s","remote":"127.0.0.1:36572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:00:35.39986Z","caller":"traceutil/trace.go:171","msg":"trace[1104612589] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:603; }","duration":"101.735093ms","start":"2026-05-29T22:00:35.298107Z","end":"2026-05-29T22:00:35.399842Z","steps":["trace[1104612589] 'read index received' (duration: 77.491232ms)","trace[1104612589] 'applied index is now lower than readState.Index' (duration: 24.242901ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:00:35.400223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.079082ms","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-05-29T22:00:35.400311Z","caller":"traceutil/trace.go:171","msg":"trace[2035911187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:584; }","duration":"102.227364ms","start":"2026-05-29T22:00:35.298063Z","end":"2026-05-29T22:00:35.40029Z","steps":["trace[2035911187] 'agreement among raft nodes before linearized reading' (duration: 101.924167ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:35.400588Z","caller":"traceutil/trace.go:171","msg":"trace[819991028] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"1.221711504s","start":"2026-05-29T22:00:34.178866Z","end":"2026-05-29T22:00:35.400578Z","steps":["trace[819991028] 'process raft request' (duration: 1.219941662s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:35.400677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:34.178855Z","time spent":"1.221753394s","remote":"127.0.0.1:36572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80688,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:00:35.401341Z","caller":"traceutil/trace.go:171","msg":"trace[1923364610] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"999.261329ms","start":"2026-05-29T22:00:34.401523Z","end":"2026-05-29T22:00:35.400785Z","steps":["trace[1923364610] 'process raft request' (duration: 998.110152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:35.401393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:34.401493Z","time spent":"999.874703ms","remote":"127.0.0.1:36572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T22:00:42.072473Z","caller":"traceutil/trace.go:171","msg":"trace[1088231013] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"483.677875ms","start":"2026-05-29T22:00:41.588767Z","end":"2026-05-29T22:00:42.072445Z","steps":["trace[1088231013] 'process raft request' (duration: 466.315624ms)","trace[1088231013] 'compare' (duration: 17.025563ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:00:42.072697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:41.588743Z","time spent":"483.88353ms","remote":"127.0.0.1:36798","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-05-29T22:00:42.48885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.697699ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T22:00:42.488959Z","caller":"traceutil/trace.go:171","msg":"trace[879059327] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"343.132755ms","start":"2026-05-29T22:00:42.145795Z","end":"2026-05-29T22:00:42.488927Z","steps":["trace[879059327] 'read index received' (duration: 52.900642ms)","trace[879059327] 'applied index is now lower than readState.Index' (duration: 290.231293ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:00:42.488991Z","caller":"traceutil/trace.go:171","msg":"trace[937543091] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"502.857999ms","start":"2026-05-29T22:00:41.986098Z","end":"2026-05-29T22:00:42.488956Z","steps":["trace[937543091] 'process raft request' (duration: 212.884603ms)","trace[937543091] 'compare' (duration: 289.016884ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:00:42.489125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:41.986065Z","time spent":"502.993982ms","remote":"127.0.0.1:36618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036221,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:00:42.489192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.36562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-q9l5f\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-29T22:00:42.489289Z","caller":"traceutil/trace.go:171","msg":"trace[1212740609] range","detail":"{range_begin:/registry/pods/kube-system/cilium-q9l5f; range_end:; response_count:1; response_revision:635; }","duration":"343.514024ms","start":"2026-05-29T22:00:42.145753Z","end":"2026-05-29T22:00:42.489267Z","steps":["trace[1212740609] 'agreement among raft nodes before linearized reading' (duration: 343.294329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:42.489344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:42.145738Z","time spent":"343.586825ms","remote":"127.0.0.1:36704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-q9l5f\" "} {"level":"warn","ts":"2026-05-29T22:00:43.025229Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410728836261250,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T22:00:43.49146Z","caller":"traceutil/trace.go:171","msg":"trace[120651860] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"984.352133ms","start":"2026-05-29T22:00:42.507087Z","end":"2026-05-29T22:00:43.491439Z","steps":["trace[120651860] 'process raft request' (duration: 984.187459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:43.491929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:42.507069Z","time spent":"984.704372ms","remote":"127.0.0.1:36704","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-05-29T22:00:43.523217Z","caller":"traceutil/trace.go:171","msg":"trace[774171219] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:658; }","duration":"998.255755ms","start":"2026-05-29T22:00:42.524935Z","end":"2026-05-29T22:00:43.52319Z","steps":["trace[774171219] 'read index received' (duration: 966.311637ms)","trace[774171219] 'applied index is now lower than readState.Index' (duration: 31.943308ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:00:43.523404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.445249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:00:43.523446Z","caller":"traceutil/trace.go:171","msg":"trace[1979066439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"998.543112ms","start":"2026-05-29T22:00:42.524891Z","end":"2026-05-29T22:00:43.523434Z","steps":["trace[1979066439] 'agreement among raft nodes before linearized reading' (duration: 998.423789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:43.523489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:42.524872Z","time spent":"998.609164ms","remote":"127.0.0.1:36528","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-05-29T22:00:43.523503Z","caller":"traceutil/trace.go:171","msg":"trace[945198179] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"980.869023ms","start":"2026-05-29T22:00:42.542457Z","end":"2026-05-29T22:00:43.523326Z","steps":["trace[945198179] 'process raft request' (duration: 980.627618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:43.523787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:42.54244Z","time spent":"981.215742ms","remote":"127.0.0.1:36880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T22:00:43.809858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.855874ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T22:00:43.810054Z","caller":"traceutil/trace.go:171","msg":"trace[879029016] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"281.118842ms","start":"2026-05-29T22:00:43.528905Z","end":"2026-05-29T22:00:43.810024Z","steps":["trace[879029016] 'process raft request' (duration: 55.009523ms)","trace[879029016] 'compare' (duration: 225.68314ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:00:43.810102Z","caller":"traceutil/trace.go:171","msg":"trace[1556430412] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"209.365232ms","start":"2026-05-29T22:00:43.600692Z","end":"2026-05-29T22:00:43.810057Z","steps":["trace[1556430412] 'process raft request' (duration: 209.28107ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:43.81008Z","caller":"traceutil/trace.go:171","msg":"trace[986367736] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"275.39237ms","start":"2026-05-29T22:00:43.534644Z","end":"2026-05-29T22:00:43.810036Z","steps":["trace[986367736] 'read index received' (duration: 49.228619ms)","trace[986367736] 'applied index is now lower than readState.Index' (duration: 226.161021ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:00:43.810265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.613795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:00:43.810326Z","caller":"traceutil/trace.go:171","msg":"trace[1464713348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"275.684657ms","start":"2026-05-29T22:00:43.534619Z","end":"2026-05-29T22:00:43.810304Z","steps":["trace[1464713348] 'agreement among raft nodes before linearized reading' (duration: 275.583974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:43.810388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.690647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:00:43.810436Z","caller":"traceutil/trace.go:171","msg":"trace[1420346152] range","detail":"{range_begin:/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:639; }","duration":"275.789108ms","start":"2026-05-29T22:00:43.534632Z","end":"2026-05-29T22:00:43.810421Z","steps":["trace[1420346152] 'agreement among raft nodes before linearized reading' (duration: 275.686606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:43.81058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.715993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-29T22:00:43.8106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.211887ms","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-05-29T22:00:43.810638Z","caller":"traceutil/trace.go:171","msg":"trace[1235834502] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:639; }","duration":"264.790104ms","start":"2026-05-29T22:00:43.54583Z","end":"2026-05-29T22:00:43.81062Z","steps":["trace[1235834502] 'agreement among raft nodes before linearized reading' (duration: 264.528298ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:43.810699Z","caller":"traceutil/trace.go:171","msg":"trace[118061907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:639; }","duration":"266.333839ms","start":"2026-05-29T22:00:43.544342Z","end":"2026-05-29T22:00:43.810676Z","steps":["trace[118061907] 'agreement among raft nodes before linearized reading' (duration: 266.054773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:43.810026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.495538ms","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-05-29T22:00:43.810826Z","caller":"traceutil/trace.go:171","msg":"trace[2086817914] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:637; }","duration":"144.768578ms","start":"2026-05-29T22:00:43.666041Z","end":"2026-05-29T22:00:43.81081Z","steps":["trace[2086817914] 'range keys from in-memory index tree' (duration: 143.462748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:44.578394Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410728836261259,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T22:00:45.079244Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410728836261259,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T22:00:45.23695Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.390777413s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-29T22:00:46.351176Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.112712732s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-29T22:00:46.35455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117260448s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T22:00:46.355495Z","caller":"traceutil/trace.go:171","msg":"trace[1200287122] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"2.53955645s","start":"2026-05-29T22:00:43.815908Z","end":"2026-05-29T22:00:46.355465Z","steps":["trace[1200287122] 'process raft request' (duration: 1.421292919s)","trace[1200287122] 'compare' (duration: 1.116814248s)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:00:46.355648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:43.815897Z","time spent":"2.539668352s","remote":"127.0.0.1:36798","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-05-29T22:00:46.356231Z","caller":"traceutil/trace.go:171","msg":"trace[1574710477] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"2.139371226s","start":"2026-05-29T22:00:44.216836Z","end":"2026-05-29T22:00:46.356207Z","steps":["trace[1574710477] 'process raft request' (duration: 2.138019724s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:46.356368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:44.216807Z","time spent":"2.139489379s","remote":"127.0.0.1:36618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:00:46.356235Z","caller":"traceutil/trace.go:171","msg":"trace[1077713207] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:663; }","duration":"2.278914103s","start":"2026-05-29T22:00:44.077292Z","end":"2026-05-29T22:00:46.356206Z","steps":["trace[1077713207] 'read index received' (duration: 1.160033337s)","trace[1077713207] 'applied index is now lower than readState.Index' (duration: 1.118878206s)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:00:46.376678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.850398623s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T22:00:46.37671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.299417097s","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-05-29T22:00:46.376739Z","caller":"traceutil/trace.go:171","msg":"trace[1909506880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"1.850556637s","start":"2026-05-29T22:00:44.526167Z","end":"2026-05-29T22:00:46.376723Z","steps":["trace[1909506880] 'agreement among raft nodes before linearized reading' (duration: 1.850462005s)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:46.376752Z","caller":"traceutil/trace.go:171","msg":"trace[551560080] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:642; }","duration":"2.299501059s","start":"2026-05-29T22:00:44.077239Z","end":"2026-05-29T22:00:46.37674Z","steps":["trace[551560080] 'agreement among raft nodes before linearized reading' (duration: 2.279034736s)","trace[551560080] 'range keys from in-memory index tree' (duration: 20.33934ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:00:46.376776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:44.526136Z","time spent":"1.850633178s","remote":"127.0.0.1:36542","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-05-29T22:00:46.376781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:44.077218Z","time spent":"2.29955746s","remote":"127.0.0.1:36798","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-05-29T22:00:46.377041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.257846ms","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-05-29T22:00:46.377068Z","caller":"traceutil/trace.go:171","msg":"trace[391461116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:642; }","duration":"531.308078ms","start":"2026-05-29T22:00:45.845753Z","end":"2026-05-29T22:00:46.377061Z","steps":["trace[391461116] 'agreement among raft nodes before linearized reading' (duration: 531.222236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:46.37709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:45.845739Z","time spent":"531.345698ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T22:00:46.377207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.396069ms","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-05-29T22:00:46.377293Z","caller":"traceutil/trace.go:171","msg":"trace[639136556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:642; }","duration":"563.537683ms","start":"2026-05-29T22:00:45.813737Z","end":"2026-05-29T22:00:46.377275Z","steps":["trace[639136556] 'agreement among raft nodes before linearized reading' (duration: 563.256686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:46.377341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:45.813709Z","time spent":"563.623054ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T22:00:47.424155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.733372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T22:00:47.424283Z","caller":"traceutil/trace.go:171","msg":"trace[1576718919] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"252.697393ms","start":"2026-05-29T22:00:47.171564Z","end":"2026-05-29T22:00:47.424261Z","steps":["trace[1576718919] 'process raft request' (duration: 144.767627ms)","trace[1576718919] 'compare' (duration: 107.616579ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:00:48.029053Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410728836261285,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T22:00:48.172475Z","caller":"traceutil/trace.go:171","msg":"trace[709822587] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:681; }","duration":"644.148257ms","start":"2026-05-29T22:00:47.528311Z","end":"2026-05-29T22:00:48.17246Z","steps":["trace[709822587] 'read index received' (duration: 544.224945ms)","trace[709822587] 'applied index is now lower than readState.Index' (duration: 99.922622ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:00:48.172565Z","caller":"traceutil/trace.go:171","msg":"trace[713163112] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"654.976807ms","start":"2026-05-29T22:00:47.51756Z","end":"2026-05-29T22:00:48.172536Z","steps":["trace[713163112] 'process raft request' (duration: 654.830404ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:48.172582Z","caller":"traceutil/trace.go:171","msg":"trace[1966597029] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"655.242124ms","start":"2026-05-29T22:00:47.517307Z","end":"2026-05-29T22:00:48.172549Z","steps":["trace[1966597029] 'process raft request' (duration: 555.22291ms)","trace[1966597029] 'compare' (duration: 99.747607ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:00:48.17268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:47.517301Z","time spent":"655.321685ms","remote":"127.0.0.1:36596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T22:00:48.172703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:47.517555Z","time spent":"655.06774ms","remote":"127.0.0.1:36704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:00:48.172918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.584196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:00:48.17315Z","caller":"traceutil/trace.go:171","msg":"trace[1285689260] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:659; }","duration":"644.646528ms","start":"2026-05-29T22:00:47.528299Z","end":"2026-05-29T22:00:48.172946Z","steps":["trace[1285689260] 'agreement among raft nodes before linearized reading' (duration: 644.30786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:48.173189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:47.528272Z","time spent":"644.894864ms","remote":"127.0.0.1:36528","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-05-29T22:00:49.111323Z","caller":"traceutil/trace.go:171","msg":"trace[1145225703] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"117.962238ms","start":"2026-05-29T22:00:48.99333Z","end":"2026-05-29T22:00:49.111292Z","steps":["trace[1145225703] 'process raft request' (duration: 114.048168ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:49.120164Z","caller":"traceutil/trace.go:171","msg":"trace[1193776064] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"126.815362ms","start":"2026-05-29T22:00:48.993332Z","end":"2026-05-29T22:00:49.120147Z","steps":["trace[1193776064] 'process raft request' (duration: 126.72828ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:49.322111Z","caller":"traceutil/trace.go:171","msg":"trace[831680654] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:709; }","duration":"149.976258ms","start":"2026-05-29T22:00:49.172109Z","end":"2026-05-29T22:00:49.322086Z","steps":["trace[831680654] 'read index received' (duration: 13.893872ms)","trace[831680654] 'applied index is now lower than readState.Index' (duration: 136.081636ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:00:49.322144Z","caller":"traceutil/trace.go:171","msg":"trace[210455071] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"163.841008ms","start":"2026-05-29T22:00:49.158275Z","end":"2026-05-29T22:00:49.322116Z","steps":["trace[210455071] 'process raft request' (duration: 155.70935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:49.322336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.209093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-dmfhm\" ","response":"range_response_count:1 size:4683"} {"level":"info","ts":"2026-05-29T22:00:49.322374Z","caller":"traceutil/trace.go:171","msg":"trace[496729273] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-dmfhm; range_end:; response_count:1; response_revision:688; }","duration":"150.300785ms","start":"2026-05-29T22:00:49.172066Z","end":"2026-05-29T22:00:49.322367Z","steps":["trace[496729273] 'agreement among raft nodes before linearized reading' (duration: 150.10161ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:49.322375Z","caller":"traceutil/trace.go:171","msg":"trace[1670931579] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"141.925401ms","start":"2026-05-29T22:00:49.180421Z","end":"2026-05-29T22:00:49.322346Z","steps":["trace[1670931579] 'process raft request' (duration: 141.612224ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:49.531441Z","caller":"traceutil/trace.go:171","msg":"trace[2147414308] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"203.376754ms","start":"2026-05-29T22:00:49.328002Z","end":"2026-05-29T22:00:49.531379Z","steps":["trace[2147414308] 'process raft request' (duration: 179.045321ms)","trace[2147414308] 'compare' (duration: 24.236311ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:00:49.723796Z","caller":"traceutil/trace.go:171","msg":"trace[403668093] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"392.977038ms","start":"2026-05-29T22:00:49.330795Z","end":"2026-05-29T22:00:49.723772Z","steps":["trace[403668093] 'process raft request' (duration: 392.702072ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:49.723909Z","caller":"traceutil/trace.go:171","msg":"trace[1812381641] linearizableReadLoop","detail":"{readStateIndex:715; appliedIndex:713; }","duration":"198.830758ms","start":"2026-05-29T22:00:49.525053Z","end":"2026-05-29T22:00:49.723884Z","steps":["trace[1812381641] 'read index received' (duration: 198.453529ms)","trace[1812381641] 'applied index is now lower than readState.Index' (duration: 375.859µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:00:49.723935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:49.330763Z","time spent":"393.100222ms","remote":"127.0.0.1:36704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4873,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:00:49.723966Z","caller":"traceutil/trace.go:171","msg":"trace[2010615932] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"362.054813ms","start":"2026-05-29T22:00:49.361882Z","end":"2026-05-29T22:00:49.723936Z","steps":["trace[2010615932] 'process raft request' (duration: 361.771637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:49.724115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:49.361861Z","time spent":"362.179227ms","remote":"127.0.0.1:36572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T22:00:49.72427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.196756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:00:49.724384Z","caller":"traceutil/trace.go:171","msg":"trace[1602352831] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:691; }","duration":"199.34116ms","start":"2026-05-29T22:00:49.525021Z","end":"2026-05-29T22:00:49.724363Z","steps":["trace[1602352831] 'agreement among raft nodes before linearized reading' (duration: 198.973472ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:49.895083Z","caller":"traceutil/trace.go:171","msg":"trace[1301319042] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:715; }","duration":"168.510566ms","start":"2026-05-29T22:00:49.726551Z","end":"2026-05-29T22:00:49.895062Z","steps":["trace[1301319042] 'read index received' (duration: 167.880482ms)","trace[1301319042] 'applied index is now lower than readState.Index' (duration: 629.034µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:00:49.895369Z","caller":"traceutil/trace.go:171","msg":"trace[918760478] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"358.558062ms","start":"2026-05-29T22:00:49.536785Z","end":"2026-05-29T22:00:49.895343Z","steps":["trace[918760478] 'process raft request' (duration: 357.670462ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:49.895454Z","caller":"traceutil/trace.go:171","msg":"trace[697015035] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"337.036484ms","start":"2026-05-29T22:00:49.558409Z","end":"2026-05-29T22:00:49.895445Z","steps":["trace[697015035] 'process raft request' (duration: 336.546673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:49.895536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:49.536767Z","time spent":"358.689224ms","remote":"127.0.0.1:36572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4318,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:00:49.895636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:49.558397Z","time spent":"337.123556ms","remote":"127.0.0.1:36572","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-05-29T22:00:49.895405Z","caller":"traceutil/trace.go:171","msg":"trace[889761139] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"192.985973ms","start":"2026-05-29T22:00:49.7024Z","end":"2026-05-29T22:00:49.895386Z","steps":["trace[889761139] 'process raft request' (duration: 192.612484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:49.895905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.338116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:00:49.896011Z","caller":"traceutil/trace.go:171","msg":"trace[964654443] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:694; }","duration":"169.4954ms","start":"2026-05-29T22:00:49.726496Z","end":"2026-05-29T22:00:49.895992Z","steps":["trace[964654443] 'agreement among raft nodes before linearized reading' (duration: 169.221453ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:49.974318Z","caller":"traceutil/trace.go:171","msg":"trace[558280711] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"237.516562ms","start":"2026-05-29T22:00:49.736776Z","end":"2026-05-29T22:00:49.974292Z","steps":["trace[558280711] 'process raft request' (duration: 237.296167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:49.974384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.986371ms","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-05-29T22:00:49.974522Z","caller":"traceutil/trace.go:171","msg":"trace[214175087] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:695; }","duration":"218.104034ms","start":"2026-05-29T22:00:49.756333Z","end":"2026-05-29T22:00:49.974437Z","steps":["trace[214175087] 'agreement among raft nodes before linearized reading' (duration: 217.94418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:49.974753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.058633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-q9l5f\" ","response":"range_response_count:1 size:17431"} {"level":"info","ts":"2026-05-29T22:00:49.974861Z","caller":"traceutil/trace.go:171","msg":"trace[439070909] range","detail":"{range_begin:/registry/pods/kube-system/cilium-q9l5f; range_end:; response_count:1; response_revision:695; }","duration":"247.183766ms","start":"2026-05-29T22:00:49.727632Z","end":"2026-05-29T22:00:49.974816Z","steps":["trace[439070909] 'agreement among raft nodes before linearized reading' (duration: 246.771197ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:52.543334Z","caller":"traceutil/trace.go:171","msg":"trace[1820766802] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:756; }","duration":"153.824877ms","start":"2026-05-29T22:00:52.389488Z","end":"2026-05-29T22:00:52.543313Z","steps":["trace[1820766802] 'read index received' (duration: 153.683384ms)","trace[1820766802] 'applied index is now lower than readState.Index' (duration: 140.493µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:00:52.543416Z","caller":"traceutil/trace.go:171","msg":"trace[244999421] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"162.438266ms","start":"2026-05-29T22:00:52.380938Z","end":"2026-05-29T22:00:52.543377Z","steps":["trace[244999421] 'process raft request' (duration: 162.242722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:52.54367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.654126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-29T22:00:52.543748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.465596ms","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-05-29T22:00:52.544359Z","caller":"traceutil/trace.go:171","msg":"trace[1704923713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:732; }","duration":"131.116182ms","start":"2026-05-29T22:00:52.413216Z","end":"2026-05-29T22:00:52.544332Z","steps":["trace[1704923713] 'agreement among raft nodes before linearized reading' (duration: 130.376864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:52.54379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.425806ms","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-05-29T22:00:52.54444Z","caller":"traceutil/trace.go:171","msg":"trace[1452101010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:732; }","duration":"131.118982ms","start":"2026-05-29T22:00:52.413306Z","end":"2026-05-29T22:00:52.544425Z","steps":["trace[1452101010] 'agreement among raft nodes before linearized reading' (duration: 130.262402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:52.543832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.331559ms","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-05-29T22:00:52.544584Z","caller":"traceutil/trace.go:171","msg":"trace[657974378] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:732; }","duration":"136.200069ms","start":"2026-05-29T22:00:52.407984Z","end":"2026-05-29T22:00:52.544184Z","steps":["trace[657974378] 'agreement among raft nodes before linearized reading' (duration: 135.529004ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:52.544605Z","caller":"traceutil/trace.go:171","msg":"trace[1818436350] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:732; }","duration":"155.138769ms","start":"2026-05-29T22:00:52.389436Z","end":"2026-05-29T22:00:52.544575Z","steps":["trace[1818436350] 'agreement among raft nodes before linearized reading' (duration: 154.009002ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:59.409305Z","caller":"traceutil/trace.go:171","msg":"trace[684508159] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"190.382453ms","start":"2026-05-29T22:00:59.21889Z","end":"2026-05-29T22:00:59.409272Z","steps":["trace[684508159] 'process raft request' (duration: 149.545779ms)","trace[684508159] 'compare' (duration: 40.631749ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:01:00.861586Z","caller":"traceutil/trace.go:171","msg":"trace[1757052646] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"140.652113ms","start":"2026-05-29T22:01:00.720911Z","end":"2026-05-29T22:01:00.861563Z","steps":["trace[1757052646] 'process raft request' (duration: 140.494069ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:01:00.861597Z","caller":"traceutil/trace.go:171","msg":"trace[438254279] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:815; }","duration":"129.538527ms","start":"2026-05-29T22:01:00.732015Z","end":"2026-05-29T22:01:00.861554Z","steps":["trace[438254279] 'read index received' (duration: 84.660528ms)","trace[438254279] 'applied index is now lower than readState.Index' (duration: 44.876269ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:01:00.861891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.845314ms","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-05-29T22:01:00.861954Z","caller":"traceutil/trace.go:171","msg":"trace[1541736440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:788; }","duration":"129.967327ms","start":"2026-05-29T22:01:00.731973Z","end":"2026-05-29T22:01:00.86194Z","steps":["trace[1541736440] 'agreement among raft nodes before linearized reading' (duration: 129.683231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:00.8621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.112886ms","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-05-29T22:01:00.862147Z","caller":"traceutil/trace.go:171","msg":"trace[1778333690] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:788; }","duration":"129.201388ms","start":"2026-05-29T22:01:00.732934Z","end":"2026-05-29T22:01:00.862136Z","steps":["trace[1778333690] 'agreement among raft nodes before linearized reading' (duration: 129.026674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:00.862309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.724041ms","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-05-29T22:01:00.862346Z","caller":"traceutil/trace.go:171","msg":"trace[262328292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:788; }","duration":"126.752032ms","start":"2026-05-29T22:01:00.735576Z","end":"2026-05-29T22:01:00.862328Z","steps":["trace[262328292] 'agreement among raft nodes before linearized reading' (duration: 126.640839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:00.862441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.929776ms","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-05-29T22:01:00.86251Z","caller":"traceutil/trace.go:171","msg":"trace[540080304] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:788; }","duration":"127.029048ms","start":"2026-05-29T22:01:00.735462Z","end":"2026-05-29T22:01:00.862491Z","steps":["trace[540080304] 'agreement among raft nodes before linearized reading' (duration: 126.814283ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:01:01.483841Z","caller":"traceutil/trace.go:171","msg":"trace[1345467457] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"121.02102ms","start":"2026-05-29T22:01:01.362779Z","end":"2026-05-29T22:01:01.4838Z","steps":["trace[1345467457] 'process raft request' (duration: 98.890528ms)","trace[1345467457] 'compare' (duration: 21.944568ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:01:01.483824Z","caller":"traceutil/trace.go:171","msg":"trace[658284228] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"116.523055ms","start":"2026-05-29T22:01:01.367274Z","end":"2026-05-29T22:01:01.483797Z","steps":["trace[658284228] 'process raft request' (duration: 116.445933ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:01:01.704882Z","caller":"traceutil/trace.go:171","msg":"trace[1985613286] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"214.46766ms","start":"2026-05-29T22:01:01.490389Z","end":"2026-05-29T22:01:01.704857Z","steps":["trace[1985613286] 'process raft request' (duration: 199.758329ms)","trace[1985613286] 'compare' (duration: 14.327372ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:01:01.705399Z","caller":"traceutil/trace.go:171","msg":"trace[311853747] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:826; }","duration":"180.283529ms","start":"2026-05-29T22:01:01.5251Z","end":"2026-05-29T22:01:01.705384Z","steps":["trace[311853747] 'read index received' (duration: 9.895899ms)","trace[311853747] 'applied index is now lower than readState.Index' (duration: 170.38673ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:01:01.705719Z","caller":"traceutil/trace.go:171","msg":"trace[1267554261] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"214.192274ms","start":"2026-05-29T22:01:01.491516Z","end":"2026-05-29T22:01:01.705708Z","steps":["trace[1267554261] 'process raft request' (duration: 213.140529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:01.706128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.007106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:01:01.706271Z","caller":"traceutil/trace.go:171","msg":"trace[232286323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:800; }","duration":"181.17082ms","start":"2026-05-29T22:01:01.525047Z","end":"2026-05-29T22:01:01.706217Z","steps":["trace[232286323] 'agreement among raft nodes before linearized reading' (duration: 180.946045ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:01:05.05141Z","caller":"traceutil/trace.go:171","msg":"trace[308544586] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"209.877653ms","start":"2026-05-29T22:01:04.841479Z","end":"2026-05-29T22:01:05.051357Z","steps":["trace[308544586] 'process raft request' (duration: 209.664819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:09.0277Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410728836261659,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T22:01:09.486074Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.195944068s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T22:01:09.486578Z","caller":"traceutil/trace.go:171","msg":"trace[657536472] linearizableReadLoop","detail":"{readStateIndex:858; appliedIndex:857; }","duration":"959.532079ms","start":"2026-05-29T22:01:08.527018Z","end":"2026-05-29T22:01:09.48655Z","steps":["trace[657536472] 'read index received' (duration: 959.311804ms)","trace[657536472] 'applied index is now lower than readState.Index' (duration: 219.265µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:01:09.48665Z","caller":"traceutil/trace.go:171","msg":"trace[1862809232] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"1.196568612s","start":"2026-05-29T22:01:08.290049Z","end":"2026-05-29T22:01:09.486617Z","steps":["trace[1862809232] 'process raft request' (duration: 1.196351227s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:09.486733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.705984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:01:09.486816Z","caller":"traceutil/trace.go:171","msg":"trace[132520618] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:828; }","duration":"959.836567ms","start":"2026-05-29T22:01:08.526962Z","end":"2026-05-29T22:01:09.486798Z","steps":["trace[132520618] 'agreement among raft nodes before linearized reading' (duration: 959.679303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:09.486848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:08.290033Z","time spent":"1.196703335s","remote":"127.0.0.1:36772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:01:09.487006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.211313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T22:01:09.487088Z","caller":"traceutil/trace.go:171","msg":"trace[217112565] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:828; }","duration":"380.335175ms","start":"2026-05-29T22:01:09.10674Z","end":"2026-05-29T22:01:09.487076Z","steps":["trace[217112565] 'agreement among raft nodes before linearized reading' (duration: 380.189901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:09.487079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.1738ms","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-05-29T22:01:09.487141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.390885ms","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-05-29T22:01:09.487153Z","caller":"traceutil/trace.go:171","msg":"trace[177870819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:828; }","duration":"325.281134ms","start":"2026-05-29T22:01:09.161857Z","end":"2026-05-29T22:01:09.487138Z","steps":["trace[177870819] 'agreement among raft nodes before linearized reading' (duration: 325.058168ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:01:09.487198Z","caller":"traceutil/trace.go:171","msg":"trace[439316807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:828; }","duration":"325.449757ms","start":"2026-05-29T22:01:09.161731Z","end":"2026-05-29T22:01:09.487181Z","steps":["trace[439316807] 'agreement among raft nodes before linearized reading' (duration: 325.331494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:09.487242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:09.161726Z","time spent":"325.508059ms","remote":"127.0.0.1:36798","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-05-29T22:01:09.487035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.400169ms","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-05-29T22:01:09.487306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.017657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-29T22:01:09.487357Z","caller":"traceutil/trace.go:171","msg":"trace[1293357386] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:828; }","duration":"326.753346ms","start":"2026-05-29T22:01:09.16059Z","end":"2026-05-29T22:01:09.487343Z","steps":["trace[1293357386] 'agreement among raft nodes before linearized reading' (duration: 326.314835ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:01:09.487388Z","caller":"traceutil/trace.go:171","msg":"trace[1593636084] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:828; }","duration":"325.126439ms","start":"2026-05-29T22:01:09.162243Z","end":"2026-05-29T22:01:09.48737Z","steps":["trace[1593636084] 'agreement among raft nodes before linearized reading' (duration: 324.886813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:09.487439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:09.162239Z","time spent":"325.18873ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T22:01:09.486865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:08.526942Z","time spent":"959.914588ms","remote":"127.0.0.1:36528","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-05-29T22:01:09.487203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:09.16185Z","time spent":"325.343835ms","remote":"127.0.0.1:36798","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-05-29T22:01:09.487144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:09.106721Z","time spent":"380.406567ms","remote":"127.0.0.1:36720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":29,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-05-29T22:01:09.486832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.308423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-29T22:01:09.489041Z","caller":"traceutil/trace.go:171","msg":"trace[1193662660] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:828; }","duration":"420.567355ms","start":"2026-05-29T22:01:09.068455Z","end":"2026-05-29T22:01:09.489022Z","steps":["trace[1193662660] 'agreement among raft nodes before linearized reading' (duration: 418.239131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:09.489131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:09.068434Z","time spent":"420.677029ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T22:01:09.487435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:09.160572Z","time spent":"326.849579ms","remote":"127.0.0.1:36798","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-05-29T22:01:44.344978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.37044ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T22:01:44.345187Z","caller":"traceutil/trace.go:171","msg":"trace[1685739624] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"133.685196ms","start":"2026-05-29T22:01:44.211484Z","end":"2026-05-29T22:01:44.34517Z","steps":["trace[1685739624] 'process raft request' (duration: 133.596684ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:01:44.345298Z","caller":"traceutil/trace.go:171","msg":"trace[924692832] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1085; }","duration":"455.492956ms","start":"2026-05-29T22:01:43.889781Z","end":"2026-05-29T22:01:44.345274Z","steps":["trace[924692832] 'read index received' (duration: 29.08µs)","trace[924692832] 'applied index is now lower than readState.Index' (duration: 455.462316ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:01:44.345514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.749572ms","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-05-29T22:01:44.345555Z","caller":"traceutil/trace.go:171","msg":"trace[974317315] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1049; }","duration":"455.834905ms","start":"2026-05-29T22:01:43.88971Z","end":"2026-05-29T22:01:44.345545Z","steps":["trace[974317315] 'agreement among raft nodes before linearized reading' (duration: 455.698911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:44.345586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:43.889694Z","time spent":"455.883475ms","remote":"127.0.0.1:36798","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-05-29T22:01:44.345649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.542222ms","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-05-29T22:01:44.34572Z","caller":"traceutil/trace.go:171","msg":"trace[1001850704] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1049; }","duration":"444.641083ms","start":"2026-05-29T22:01:43.901059Z","end":"2026-05-29T22:01:44.3457Z","steps":["trace[1001850704] 'agreement among raft nodes before linearized reading' (duration: 444.404648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:44.345777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:43.901045Z","time spent":"444.717906ms","remote":"127.0.0.1:36798","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-05-29T22:01:44.345928Z","caller":"traceutil/trace.go:171","msg":"trace[233532657] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"492.99234ms","start":"2026-05-29T22:01:43.852173Z","end":"2026-05-29T22:01:44.345165Z","steps":["trace[233532657] 'process raft request' (duration: 23.330373ms)","trace[233532657] 'compare' (duration: 469.190166ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:01:44.346026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:43.852154Z","time spent":"493.82663ms","remote":"127.0.0.1:36798","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-05-29T22:01:45.77857Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410728836262111,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T22:01:46.279063Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410728836262111,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T22:01:46.696243Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.506660307s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T22:01:46.696778Z","caller":"traceutil/trace.go:171","msg":"trace[1602718531] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"1.50723905s","start":"2026-05-29T22:01:45.189494Z","end":"2026-05-29T22:01:46.696733Z","steps":["trace[1602718531] 'process raft request' (duration: 1.506968144s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:46.697042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:45.18947Z","time spent":"1.507431405s","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:01:47.49703Z","caller":"traceutil/trace.go:171","msg":"trace[1561255061] linearizableReadLoop","detail":"{readStateIndex:1091; appliedIndex:1089; }","duration":"2.219081822s","start":"2026-05-29T22:01:45.277926Z","end":"2026-05-29T22:01:47.497008Z","steps":["trace[1561255061] 'read index received' (duration: 1.418516703s)","trace[1561255061] 'applied index is now lower than readState.Index' (duration: 800.564019ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:01:47.497037Z","caller":"traceutil/trace.go:171","msg":"trace[1207278581] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"1.146944083s","start":"2026-05-29T22:01:46.350072Z","end":"2026-05-29T22:01:47.497016Z","steps":["trace[1207278581] 'process raft request' (duration: 1.14681478s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:47.497165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.219231585s","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":"warn","ts":"2026-05-29T22:01:47.497189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:46.350047Z","time spent":"1.147059045s","remote":"127.0.0.1:36798","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-05-29T22:01:47.49721Z","caller":"traceutil/trace.go:171","msg":"trace[882057354] 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:1053; }","duration":"2.219326997s","start":"2026-05-29T22:01:45.277873Z","end":"2026-05-29T22:01:47.4972Z","steps":["trace[882057354] 'agreement among raft nodes before linearized reading' (duration: 2.219245766s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:47.497247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:45.277845Z","time spent":"2.219392699s","remote":"127.0.0.1:37538","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-05-29T22:01:47.497305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.971525052s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T22:01:47.497351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.646056076s","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-05-29T22:01:47.497732Z","caller":"traceutil/trace.go:171","msg":"trace[1355015640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1053; }","duration":"1.646475755s","start":"2026-05-29T22:01:45.851237Z","end":"2026-05-29T22:01:47.497713Z","steps":["trace[1355015640] 'agreement among raft nodes before linearized reading' (duration: 1.645994264s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:47.497773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:45.851217Z","time spent":"1.646547437s","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T22:01:47.49735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14726708s","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-05-29T22:01:47.497829Z","caller":"traceutil/trace.go:171","msg":"trace[1127576264] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1053; }","duration":"1.147799343s","start":"2026-05-29T22:01:46.350017Z","end":"2026-05-29T22:01:47.497817Z","steps":["trace[1127576264] 'agreement among raft nodes before linearized reading' (duration: 1.147200559s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:47.497864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:46.349998Z","time spent":"1.147855023s","remote":"127.0.0.1:36798","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":"info","ts":"2026-05-29T22:01:47.497386Z","caller":"traceutil/trace.go:171","msg":"trace[1517715097] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1053; }","duration":"1.971623514s","start":"2026-05-29T22:01:45.525743Z","end":"2026-05-29T22:01:47.497366Z","steps":["trace[1517715097] 'agreement among raft nodes before linearized reading' (duration: 1.971461101s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:47.497954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:45.525689Z","time spent":"1.972256709s","remote":"127.0.0.1:36528","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-05-29T22:01:47.497445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070504381s","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-05-29T22:01:47.498021Z","caller":"traceutil/trace.go:171","msg":"trace[566090800] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1053; }","duration":"1.071125975s","start":"2026-05-29T22:01:46.426888Z","end":"2026-05-29T22:01:47.498014Z","steps":["trace[566090800] 'agreement among raft nodes before linearized reading' (duration: 1.070503561s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:47.498048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:46.426857Z","time spent":"1.071180667s","remote":"127.0.0.1:36798","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-05-29T22:01:47.497553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.694779161s","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-05-29T22:01:47.498099Z","caller":"traceutil/trace.go:171","msg":"trace[444375643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1053; }","duration":"1.695362444s","start":"2026-05-29T22:01:45.802731Z","end":"2026-05-29T22:01:47.498093Z","steps":["trace[444375643] 'agreement among raft nodes before linearized reading' (duration: 1.694700129s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:47.498119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:45.802711Z","time spent":"1.695403145s","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T22:01:47.497664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.119891252s","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-05-29T22:01:47.49817Z","caller":"traceutil/trace.go:171","msg":"trace[1182473282] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1053; }","duration":"1.120420485s","start":"2026-05-29T22:01:46.377744Z","end":"2026-05-29T22:01:47.498164Z","steps":["trace[1182473282] 'agreement among raft nodes before linearized reading' (duration: 1.119848601s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:47.498188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:46.377732Z","time spent":"1.120450255s","remote":"127.0.0.1:36798","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-05-29T22:01:49.690453Z","caller":"traceutil/trace.go:171","msg":"trace[1504329325] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1097; }","duration":"181.842689ms","start":"2026-05-29T22:01:49.508596Z","end":"2026-05-29T22:01:49.690438Z","steps":["trace[1504329325] 'read index received' (duration: 181.752867ms)","trace[1504329325] 'applied index is now lower than readState.Index' (duration: 89.062µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:01:49.690523Z","caller":"traceutil/trace.go:171","msg":"trace[727895783] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"188.76614ms","start":"2026-05-29T22:01:49.501735Z","end":"2026-05-29T22:01:49.690501Z","steps":["trace[727895783] 'process raft request' (duration: 188.612997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:49.690618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.012592ms","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-05-29T22:01:49.690709Z","caller":"traceutil/trace.go:171","msg":"trace[1346169463] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1059; }","duration":"182.155186ms","start":"2026-05-29T22:01:49.508546Z","end":"2026-05-29T22:01:49.690702Z","steps":["trace[1346169463] 'agreement among raft nodes before linearized reading' (duration: 181.978512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:49.690686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.062174ms","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-05-29T22:01:49.690854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.91318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-29T22:01:49.690903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.732873ms","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-05-29T22:01:49.69095Z","caller":"traceutil/trace.go:171","msg":"trace[1478147711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1059; }","duration":"182.049114ms","start":"2026-05-29T22:01:49.508874Z","end":"2026-05-29T22:01:49.690923Z","steps":["trace[1478147711] 'agreement among raft nodes before linearized reading' (duration: 181.718956ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:01:49.690875Z","caller":"traceutil/trace.go:171","msg":"trace[1046240068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1059; }","duration":"182.292519ms","start":"2026-05-29T22:01:49.508564Z","end":"2026-05-29T22:01:49.690856Z","steps":["trace[1046240068] 'agreement among raft nodes before linearized reading' (duration: 181.954912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:49.690749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.853213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:01:49.691092Z","caller":"traceutil/trace.go:171","msg":"trace[445927352] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1059; }","duration":"165.21585ms","start":"2026-05-29T22:01:49.525868Z","end":"2026-05-29T22:01:49.691084Z","steps":["trace[445927352] 'agreement among raft nodes before linearized reading' (duration: 164.861742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:49.690873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.855166ms","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-05-29T22:01:49.690994Z","caller":"traceutil/trace.go:171","msg":"trace[1072236136] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1059; }","duration":"180.839266ms","start":"2026-05-29T22:01:49.510129Z","end":"2026-05-29T22:01:49.690968Z","steps":["trace[1072236136] 'agreement among raft nodes before linearized reading' (duration: 180.56657ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:01:49.691155Z","caller":"traceutil/trace.go:171","msg":"trace[285755939] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1059; }","duration":"181.159063ms","start":"2026-05-29T22:01:49.509981Z","end":"2026-05-29T22:01:49.69114Z","steps":["trace[285755939] 'agreement among raft nodes before linearized reading' (duration: 180.759154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:10.785874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.32228ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T22:02:10.78598Z","caller":"traceutil/trace.go:171","msg":"trace[852505523] linearizableReadLoop","detail":"{readStateIndex:1169; appliedIndex:1168; }","duration":"961.949159ms","start":"2026-05-29T22:02:09.824015Z","end":"2026-05-29T22:02:10.785964Z","steps":["trace[852505523] 'read index received' (duration: 95.387973ms)","trace[852505523] 'applied index is now lower than readState.Index' (duration: 866.559706ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:02:10.78612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.096483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:02:10.786187Z","caller":"traceutil/trace.go:171","msg":"trace[352865861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1126; }","duration":"962.188694ms","start":"2026-05-29T22:02:09.823982Z","end":"2026-05-29T22:02:10.78617Z","steps":["trace[352865861] 'agreement among raft nodes before linearized reading' (duration: 962.082362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:10.786283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:09.823969Z","time spent":"962.297596ms","remote":"127.0.0.1:36528","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-05-29T22:02:10.78663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"960.757372ms","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-05-29T22:02:10.78668Z","caller":"traceutil/trace.go:171","msg":"trace[460019043] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1126; }","duration":"960.832623ms","start":"2026-05-29T22:02:09.825833Z","end":"2026-05-29T22:02:10.786666Z","steps":["trace[460019043] 'agreement among raft nodes before linearized reading' (duration: 960.690418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:10.786731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.018504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:02:10.786784Z","caller":"traceutil/trace.go:171","msg":"trace[911207733] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"991.963889ms","start":"2026-05-29T22:02:09.794025Z","end":"2026-05-29T22:02:10.785989Z","steps":["trace[911207733] 'process raft request' (duration: 125.419234ms)","trace[911207733] 'compare' (duration: 866.154366ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:02:10.7869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:09.82582Z","time spent":"960.932376ms","remote":"127.0.0.1:36798","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-05-29T22:02:10.78686Z","caller":"traceutil/trace.go:171","msg":"trace[1595889556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1126; }","duration":"261.203668ms","start":"2026-05-29T22:02:10.525615Z","end":"2026-05-29T22:02:10.786818Z","steps":["trace[1595889556] 'agreement among raft nodes before linearized reading' (duration: 261.004824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:10.786965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:09.794012Z","time spent":"992.85773ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:02:10.787095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.440212ms","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-05-29T22:02:10.787144Z","caller":"traceutil/trace.go:171","msg":"trace[1029452834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1126; }","duration":"947.513983ms","start":"2026-05-29T22:02:09.839622Z","end":"2026-05-29T22:02:10.787136Z","steps":["trace[1029452834] 'agreement among raft nodes before linearized reading' (duration: 947.423941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:10.78713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.622754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-29T22:02:10.787181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:09.839605Z","time spent":"947.569225ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-29T22:02:10.787207Z","caller":"traceutil/trace.go:171","msg":"trace[806671755] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:1126; }","duration":"284.737567ms","start":"2026-05-29T22:02:10.502452Z","end":"2026-05-29T22:02:10.78719Z","steps":["trace[806671755] 'agreement among raft nodes before linearized reading' (duration: 284.565283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:10.787349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.528833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-29T22:02:10.787132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.220896ms","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-05-29T22:02:10.787427Z","caller":"traceutil/trace.go:171","msg":"trace[1041633364] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1126; }","duration":"947.607275ms","start":"2026-05-29T22:02:09.839801Z","end":"2026-05-29T22:02:10.787408Z","steps":["trace[1041633364] 'agreement among raft nodes before linearized reading' (duration: 947.42266ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:10.787433Z","caller":"traceutil/trace.go:171","msg":"trace[1381284862] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1126; }","duration":"947.551464ms","start":"2026-05-29T22:02:09.839864Z","end":"2026-05-29T22:02:10.787415Z","steps":["trace[1381284862] 'agreement among raft nodes before linearized reading' (duration: 947.061233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:10.787491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:09.839856Z","time spent":"947.623075ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T22:02:10.787493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:09.839695Z","time spent":"947.777569ms","remote":"127.0.0.1:36798","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":"info","ts":"2026-05-29T22:02:10.960805Z","caller":"traceutil/trace.go:171","msg":"trace[1647785627] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"168.542897ms","start":"2026-05-29T22:02:10.792239Z","end":"2026-05-29T22:02:10.960782Z","steps":["trace[1647785627] 'process raft request' (duration: 113.115366ms)","trace[1647785627] 'compare' (duration: 55.213056ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:02:10.961072Z","caller":"traceutil/trace.go:171","msg":"trace[517159401] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"168.327293ms","start":"2026-05-29T22:02:10.792725Z","end":"2026-05-29T22:02:10.961052Z","steps":["trace[517159401] 'process raft request' (duration: 168.21989ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:10.961095Z","caller":"traceutil/trace.go:171","msg":"trace[1871632370] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"168.744432ms","start":"2026-05-29T22:02:10.792295Z","end":"2026-05-29T22:02:10.96104Z","steps":["trace[1871632370] 'process raft request' (duration: 168.450126ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:10.961114Z","caller":"traceutil/trace.go:171","msg":"trace[763900050] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"129.009887ms","start":"2026-05-29T22:02:10.832088Z","end":"2026-05-29T22:02:10.961098Z","steps":["trace[763900050] 'process raft request' (duration: 128.966436ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:10.961217Z","caller":"traceutil/trace.go:171","msg":"trace[1816079466] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"167.594026ms","start":"2026-05-29T22:02:10.793562Z","end":"2026-05-29T22:02:10.961156Z","steps":["trace[1816079466] 'process raft request' (duration: 167.456983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:13.466108Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410728836262330,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T22:02:13.966885Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410728836262330,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T22:02:14.467893Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410728836262330,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T22:02:14.600772Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.800683407s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T22:02:14.601163Z","caller":"traceutil/trace.go:171","msg":"trace[965264616] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"1.801398534s","start":"2026-05-29T22:02:12.79974Z","end":"2026-05-29T22:02:14.601139Z","steps":["trace[965264616] 'process raft request' (duration: 1.80123524s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:14.601315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:12.799705Z","time spent":"1.801534007s","remote":"127.0.0.1:36798","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-05-29T22:02:14.654563Z","caller":"traceutil/trace.go:171","msg":"trace[1133622807] linearizableReadLoop","detail":"{readStateIndex:1179; appliedIndex:1177; }","duration":"1.689171017s","start":"2026-05-29T22:02:12.96537Z","end":"2026-05-29T22:02:14.654541Z","steps":["trace[1133622807] 'read index received' (duration: 1.635588018s)","trace[1133622807] 'applied index is now lower than readState.Index' (duration: 53.582059ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:02:14.654668Z","caller":"traceutil/trace.go:171","msg":"trace[1472205767] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"861.342082ms","start":"2026-05-29T22:02:13.793304Z","end":"2026-05-29T22:02:14.654646Z","steps":["trace[1472205767] 'process raft request' (duration: 861.112237ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:14.654831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:13.793278Z","time spent":"861.496456ms","remote":"127.0.0.1:36798","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-05-29T22:02:14.654846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.689457333s","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-05-29T22:02:14.654885Z","caller":"traceutil/trace.go:171","msg":"trace[1514769915] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1135; }","duration":"1.689547935s","start":"2026-05-29T22:02:12.965326Z","end":"2026-05-29T22:02:14.654874Z","steps":["trace[1514769915] 'agreement among raft nodes before linearized reading' (duration: 1.68932699s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:14.654872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.689115106s","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-05-29T22:02:14.654917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:12.965298Z","time spent":"1.689612157s","remote":"127.0.0.1:36798","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":"info","ts":"2026-05-29T22:02:14.65493Z","caller":"traceutil/trace.go:171","msg":"trace[562893203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1135; }","duration":"1.689181927s","start":"2026-05-29T22:02:12.965732Z","end":"2026-05-29T22:02:14.654914Z","steps":["trace[562893203] 'agreement among raft nodes before linearized reading' (duration: 1.689019784s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:14.654956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.831242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T22:02:14.655004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:12.965707Z","time spent":"1.689285449s","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-29T22:02:14.655021Z","caller":"traceutil/trace.go:171","msg":"trace[2120935359] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1135; }","duration":"128.931063ms","start":"2026-05-29T22:02:14.526072Z","end":"2026-05-29T22:02:14.655003Z","steps":["trace[2120935359] 'agreement among raft nodes before linearized reading' (duration: 128.826791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:14.65503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.688254345s","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-05-29T22:02:14.655064Z","caller":"traceutil/trace.go:171","msg":"trace[467174106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1135; }","duration":"1.688306917s","start":"2026-05-29T22:02:12.966747Z","end":"2026-05-29T22:02:14.655054Z","steps":["trace[467174106] 'agreement among raft nodes before linearized reading' (duration: 1.688192694s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:14.6551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:12.966738Z","time spent":"1.688354977s","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T22:02:14.655184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.689241379s","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-05-29T22:02:14.655263Z","caller":"traceutil/trace.go:171","msg":"trace[663543625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1135; }","duration":"1.689362301s","start":"2026-05-29T22:02:12.965882Z","end":"2026-05-29T22:02:14.655244Z","steps":["trace[663543625] 'agreement among raft nodes before linearized reading' (duration: 1.689161876s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:14.655311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:12.965873Z","time spent":"1.689428883s","remote":"127.0.0.1:36798","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-05-29T22:02:17.083634Z","caller":"traceutil/trace.go:171","msg":"trace[212860248] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"472.607996ms","start":"2026-05-29T22:02:16.610993Z","end":"2026-05-29T22:02:17.083601Z","steps":["trace[212860248] 'process raft request' (duration: 472.429792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:17.083822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:16.610969Z","time spent":"472.752469ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:02:18.893634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.658430022s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T22:02:18.893818Z","caller":"traceutil/trace.go:171","msg":"trace[1690129030] linearizableReadLoop","detail":"{readStateIndex:1186; appliedIndex:1184; }","duration":"2.21565929s","start":"2026-05-29T22:02:16.678142Z","end":"2026-05-29T22:02:18.893801Z","steps":["trace[1690129030] 'read index received' (duration: 405.302516ms)","trace[1690129030] 'applied index is now lower than readState.Index' (duration: 1.810355384s)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:02:18.893929Z","caller":"traceutil/trace.go:171","msg":"trace[2027295253] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"2.23367863s","start":"2026-05-29T22:02:16.660238Z","end":"2026-05-29T22:02:18.893917Z","steps":["trace[2027295253] 'process raft request' (duration: 574.860308ms)","trace[2027295253] 'compare' (duration: 1.658030873s)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:02:18.894001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:16.660146Z","time spent":"2.233818683s","remote":"127.0.0.1:36798","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-05-29T22:02:18.894041Z","caller":"traceutil/trace.go:171","msg":"trace[440256438] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"1.731083825s","start":"2026-05-29T22:02:17.162923Z","end":"2026-05-29T22:02:18.894007Z","steps":["trace[440256438] 'process raft request' (duration: 1.730822898s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:18.894216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:17.162901Z","time spent":"1.731227388s","remote":"127.0.0.1:36798","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-05-29T22:02:18.894329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.216194742s","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-05-29T22:02:18.894371Z","caller":"traceutil/trace.go:171","msg":"trace[734535147] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1142; }","duration":"2.216238012s","start":"2026-05-29T22:02:16.678123Z","end":"2026-05-29T22:02:18.894361Z","steps":["trace[734535147] 'agreement among raft nodes before linearized reading' (duration: 2.216055518s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:18.894387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.167770624s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-29T22:02:18.894409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:16.678068Z","time spent":"2.216334805s","remote":"127.0.0.1:36798","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-05-29T22:02:18.894424Z","caller":"traceutil/trace.go:171","msg":"trace[1093259212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1142; }","duration":"2.167892017s","start":"2026-05-29T22:02:16.726522Z","end":"2026-05-29T22:02:18.894414Z","steps":["trace[1093259212] 'agreement among raft nodes before linearized reading' (duration: 2.167765654s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:18.894456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:16.726497Z","time spent":"2.167950907s","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T22:02:18.89455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.342681534s","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":"warn","ts":"2026-05-29T22:02:18.894576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.167481637s","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-05-29T22:02:18.894588Z","caller":"traceutil/trace.go:171","msg":"trace[1261561692] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1142; }","duration":"1.342721324s","start":"2026-05-29T22:02:17.551857Z","end":"2026-05-29T22:02:18.894579Z","steps":["trace[1261561692] 'agreement among raft nodes before linearized reading' (duration: 1.342665733s)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:18.894604Z","caller":"traceutil/trace.go:171","msg":"trace[442016814] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1142; }","duration":"2.167528108s","start":"2026-05-29T22:02:16.727068Z","end":"2026-05-29T22:02:18.894596Z","steps":["trace[442016814] 'agreement among raft nodes before linearized reading' (duration: 2.167457236s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:18.894618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:17.551799Z","time spent":"1.342810926s","remote":"127.0.0.1:37536","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-05-29T22:02:18.894691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.167784694s","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-05-29T22:02:18.894725Z","caller":"traceutil/trace.go:171","msg":"trace[521789703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1142; }","duration":"2.167834936s","start":"2026-05-29T22:02:16.726883Z","end":"2026-05-29T22:02:18.894718Z","steps":["trace[521789703] 'agreement among raft nodes before linearized reading' (duration: 2.167761914s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:18.89475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:16.726876Z","time spent":"2.167866856s","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T22:02:18.894715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.127219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T22:02:18.894779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.28048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:02:18.894813Z","caller":"traceutil/trace.go:171","msg":"trace[171293674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1142; }","duration":"552.352772ms","start":"2026-05-29T22:02:18.342451Z","end":"2026-05-29T22:02:18.894804Z","steps":["trace[171293674] 'agreement among raft nodes before linearized reading' (duration: 552.305971ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:18.894806Z","caller":"traceutil/trace.go:171","msg":"trace[1069311393] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1142; }","duration":"368.266612ms","start":"2026-05-29T22:02:18.526518Z","end":"2026-05-29T22:02:18.894784Z","steps":["trace[1069311393] 'agreement among raft nodes before linearized reading' (duration: 368.088498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:18.894891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:18.526495Z","time spent":"368.386174ms","remote":"127.0.0.1:36542","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-05-29T22:02:18.894849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:18.34243Z","time spent":"552.413924ms","remote":"127.0.0.1:36528","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-05-29T22:02:18.894631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:16.727056Z","time spent":"2.16756766s","remote":"127.0.0.1:36798","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-05-29T22:02:19.61794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.344997ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T22:02:19.618049Z","caller":"traceutil/trace.go:171","msg":"trace[1782268227] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"718.884327ms","start":"2026-05-29T22:02:18.899146Z","end":"2026-05-29T22:02:19.61803Z","steps":["trace[1782268227] 'process raft request' (duration: 377.369617ms)","trace[1782268227] 'compare' (duration: 341.220073ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:02:19.618109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:18.899129Z","time spent":"718.952408ms","remote":"127.0.0.1:36798","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-05-29T22:02:19.682876Z","caller":"traceutil/trace.go:171","msg":"trace[2136434651] linearizableReadLoop","detail":"{readStateIndex:1191; appliedIndex:1187; }","duration":"593.877273ms","start":"2026-05-29T22:02:19.088978Z","end":"2026-05-29T22:02:19.682855Z","steps":["trace[2136434651] 'read index received' (duration: 187.508531ms)","trace[2136434651] 'applied index is now lower than readState.Index' (duration: 406.367882ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:02:19.683066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.087209ms","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-05-29T22:02:19.683106Z","caller":"traceutil/trace.go:171","msg":"trace[970584466] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1146; }","duration":"594.174361ms","start":"2026-05-29T22:02:19.088922Z","end":"2026-05-29T22:02:19.683096Z","steps":["trace[970584466] 'agreement among raft nodes before linearized reading' (duration: 594.019307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:19.683139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:19.088895Z","time spent":"594.237382ms","remote":"127.0.0.1:36798","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":"info","ts":"2026-05-29T22:02:19.683371Z","caller":"traceutil/trace.go:171","msg":"trace[19004485] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"783.83854ms","start":"2026-05-29T22:02:18.899488Z","end":"2026-05-29T22:02:19.683327Z","steps":["trace[19004485] 'process raft request' (duration: 783.188965ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:19.683472Z","caller":"traceutil/trace.go:171","msg":"trace[2037667548] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"782.679223ms","start":"2026-05-29T22:02:18.900785Z","end":"2026-05-29T22:02:19.683465Z","steps":["trace[2037667548] 'process raft request' (duration: 782.031008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:19.683538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.218751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T22:02:19.683627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:18.899468Z","time spent":"784.033064ms","remote":"127.0.0.1:36798","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-05-29T22:02:19.683725Z","caller":"traceutil/trace.go:171","msg":"trace[919557699] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1146; }","duration":"150.476547ms","start":"2026-05-29T22:02:19.533182Z","end":"2026-05-29T22:02:19.683659Z","steps":["trace[919557699] 'agreement among raft nodes before linearized reading' (duration: 150.19494ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:19.68344Z","caller":"traceutil/trace.go:171","msg":"trace[1486934435] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"783.033051ms","start":"2026-05-29T22:02:18.900395Z","end":"2026-05-29T22:02:19.683428Z","steps":["trace[1486934435] 'process raft request' (duration: 782.383936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:19.683931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:18.900378Z","time spent":"783.474762ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:02:19.684146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:18.900777Z","time spent":"783.265037ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:02:19.808069Z","caller":"traceutil/trace.go:171","msg":"trace[2052874736] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"117.648332ms","start":"2026-05-29T22:02:19.690376Z","end":"2026-05-29T22:02:19.808025Z","steps":["trace[2052874736] 'process raft request' (duration: 27.283316ms)","trace[2052874736] 'compare' (duration: 90.195162ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:02:31.754668Z","caller":"traceutil/trace.go:171","msg":"trace[1664154975] linearizableReadLoop","detail":"{readStateIndex:1279; appliedIndex:1277; }","duration":"166.520471ms","start":"2026-05-29T22:02:31.588124Z","end":"2026-05-29T22:02:31.754644Z","steps":["trace[1664154975] 'read index received' (duration: 78.627782ms)","trace[1664154975] 'applied index is now lower than readState.Index' (duration: 87.891809ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:02:31.754803Z","caller":"traceutil/trace.go:171","msg":"trace[874304370] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"194.077274ms","start":"2026-05-29T22:02:31.560714Z","end":"2026-05-29T22:02:31.754791Z","steps":["trace[874304370] 'process raft request' (duration: 193.834349ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:31.754841Z","caller":"traceutil/trace.go:171","msg":"trace[1114060495] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"195.354433ms","start":"2026-05-29T22:02:31.559464Z","end":"2026-05-29T22:02:31.754818Z","steps":["trace[1114060495] 'process raft request' (duration: 107.30909ms)","trace[1114060495] 'compare' (duration: 87.605443ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:02:31.755486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.353511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363686"} {"level":"info","ts":"2026-05-29T22:02:31.755527Z","caller":"traceutil/trace.go:171","msg":"trace[2102790813] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:1229; }","duration":"167.440804ms","start":"2026-05-29T22:02:31.588075Z","end":"2026-05-29T22:02:31.755516Z","steps":["trace[2102790813] 'agreement among raft nodes before linearized reading' (duration: 167.134547ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:36.240719Z","caller":"traceutil/trace.go:171","msg":"trace[1777191817] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"452.882236ms","start":"2026-05-29T22:02:35.787805Z","end":"2026-05-29T22:02:36.240687Z","steps":["trace[1777191817] 'process raft request' (duration: 452.725782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:36.240886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:35.787775Z","time spent":"453.024909ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:02:36.257247Z","caller":"traceutil/trace.go:171","msg":"trace[1868863728] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"469.208097ms","start":"2026-05-29T22:02:35.788001Z","end":"2026-05-29T22:02:36.257209Z","steps":["trace[1868863728] 'process raft request' (duration: 468.980272ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:36.257392Z","caller":"traceutil/trace.go:171","msg":"trace[1213940299] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"468.328976ms","start":"2026-05-29T22:02:35.789037Z","end":"2026-05-29T22:02:36.257366Z","steps":["trace[1213940299] 'process raft request' (duration: 468.060739ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:36.257421Z","caller":"traceutil/trace.go:171","msg":"trace[254271640] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"466.926323ms","start":"2026-05-29T22:02:35.790468Z","end":"2026-05-29T22:02:36.257394Z","steps":["trace[254271640] 'process raft request' (duration: 466.679598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:36.25745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:35.78798Z","time spent":"469.3653ms","remote":"127.0.0.1:36798","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-05-29T22:02:36.2575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:35.790455Z","time spent":"466.994105ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:02:36.257501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:35.789024Z","time spent":"468.424048ms","remote":"127.0.0.1:36798","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-05-29T22:02:36.260535Z","caller":"traceutil/trace.go:171","msg":"trace[866367083] linearizableReadLoop","detail":"{readStateIndex:1302; appliedIndex:1297; }","duration":"381.736917ms","start":"2026-05-29T22:02:35.878768Z","end":"2026-05-29T22:02:36.260505Z","steps":["trace[866367083] 'read index received' (duration: 361.801712ms)","trace[866367083] 'applied index is now lower than readState.Index' (duration: 19.933825ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:02:36.260718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.947092ms","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-05-29T22:02:36.260763Z","caller":"traceutil/trace.go:171","msg":"trace[1133198957] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1251; }","duration":"382.023794ms","start":"2026-05-29T22:02:35.878728Z","end":"2026-05-29T22:02:36.260752Z","steps":["trace[1133198957] 'agreement among raft nodes before linearized reading' (duration: 381.87017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:36.260793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:35.878712Z","time spent":"382.075415ms","remote":"127.0.0.1:36798","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-05-29T22:02:37.949618Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410728836262585,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T22:02:38.450062Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410728836262585,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T22:02:38.734323Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.778657016s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T22:02:38.734584Z","caller":"traceutil/trace.go:171","msg":"trace[1207750799] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1303; }","duration":"1.285305648s","start":"2026-05-29T22:02:37.449261Z","end":"2026-05-29T22:02:38.734566Z","steps":["trace[1207750799] 'read index received' (duration: 1.285143604s)","trace[1207750799] 'applied index is now lower than readState.Index' (duration: 161.464µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:02:38.73465Z","caller":"traceutil/trace.go:171","msg":"trace[1509655084] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"1.779040944s","start":"2026-05-29T22:02:36.955601Z","end":"2026-05-29T22:02:38.734642Z","steps":["trace[1509655084] 'process raft request' (duration: 1.77884911s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:38.73473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:36.955577Z","time spent":"1.779096116s","remote":"127.0.0.1:36798","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-05-29T22:02:38.734849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.677444ms","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-05-29T22:02:38.734879Z","caller":"traceutil/trace.go:171","msg":"trace[1270183397] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1253; }","duration":"462.723865ms","start":"2026-05-29T22:02:38.272147Z","end":"2026-05-29T22:02:38.73487Z","steps":["trace[1270183397] 'agreement among raft nodes before linearized reading' (duration: 462.614782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:38.734872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.846541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-29T22:02:38.734903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:38.27214Z","time spent":"462.757325ms","remote":"127.0.0.1:36798","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-05-29T22:02:38.734868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.285562903s","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":"warn","ts":"2026-05-29T22:02:38.734899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.994155ms","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-05-29T22:02:38.734931Z","caller":"traceutil/trace.go:171","msg":"trace[153848355] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1253; }","duration":"472.958244ms","start":"2026-05-29T22:02:38.261958Z","end":"2026-05-29T22:02:38.734916Z","steps":["trace[153848355] 'agreement among raft nodes before linearized reading' (duration: 472.760719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:38.734726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207512494s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T22:02:38.734898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.983734ms","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-05-29T22:02:38.734966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:38.26194Z","time spent":"473.019285ms","remote":"127.0.0.1:36798","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-05-29T22:02:38.734976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.83587ms","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-05-29T22:02:38.73499Z","caller":"traceutil/trace.go:171","msg":"trace[1474721166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1253; }","duration":"473.115147ms","start":"2026-05-29T22:02:38.26187Z","end":"2026-05-29T22:02:38.734985Z","steps":["trace[1474721166] 'agreement among raft nodes before linearized reading' (duration: 472.918433ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:38.73499Z","caller":"traceutil/trace.go:171","msg":"trace[1394736014] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1253; }","duration":"1.207817251s","start":"2026-05-29T22:02:37.527164Z","end":"2026-05-29T22:02:38.734981Z","steps":["trace[1394736014] 'agreement among raft nodes before linearized reading' (duration: 1.207521494s)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:38.735004Z","caller":"traceutil/trace.go:171","msg":"trace[1556474271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1253; }","duration":"490.86261ms","start":"2026-05-29T22:02:38.244135Z","end":"2026-05-29T22:02:38.734997Z","steps":["trace[1556474271] 'agreement among raft nodes before linearized reading' (duration: 490.803749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:38.735015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:38.261858Z","time spent":"473.149518ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T22:02:38.735027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:38.244098Z","time spent":"490.921332ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T22:02:38.735034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:37.527144Z","time spent":"1.207865072s","remote":"127.0.0.1:36528","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-05-29T22:02:38.734954Z","caller":"traceutil/trace.go:171","msg":"trace[140686601] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1253; }","duration":"1.285705736s","start":"2026-05-29T22:02:37.449229Z","end":"2026-05-29T22:02:38.734935Z","steps":["trace[140686601] 'agreement among raft nodes before linearized reading' (duration: 1.285516262s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:38.735112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:37.449215Z","time spent":"1.285883321s","remote":"127.0.0.1:37554","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-05-29T22:02:38.734961Z","caller":"traceutil/trace.go:171","msg":"trace[212793755] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1253; }","duration":"473.085066ms","start":"2026-05-29T22:02:38.261859Z","end":"2026-05-29T22:02:38.734944Z","steps":["trace[212793755] 'agreement among raft nodes before linearized reading' (duration: 472.925823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:38.73522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:38.261814Z","time spent":"473.384913ms","remote":"127.0.0.1:36798","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-05-29T22:02:39.242093Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410728836262589,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T22:02:39.538556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.13295ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T22:02:39.538681Z","caller":"traceutil/trace.go:171","msg":"trace[1770799608] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"800.643952ms","start":"2026-05-29T22:02:38.738014Z","end":"2026-05-29T22:02:39.538658Z","steps":["trace[1770799608] 'process raft request' (duration: 520.323688ms)","trace[1770799608] 'compare' (duration: 279.820683ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:02:39.538768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:38.737965Z","time spent":"800.761615ms","remote":"127.0.0.1:36798","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-05-29T22:02:39.893063Z","caller":"traceutil/trace.go:171","msg":"trace[760291121] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"1.152765209s","start":"2026-05-29T22:02:38.740275Z","end":"2026-05-29T22:02:39.89304Z","steps":["trace[760291121] 'process raft request' (duration: 1.152670957s)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:39.893155Z","caller":"traceutil/trace.go:171","msg":"trace[501015379] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1304; }","duration":"1.151849828s","start":"2026-05-29T22:02:38.741294Z","end":"2026-05-29T22:02:39.893144Z","steps":["trace[501015379] 'read index received' (duration: 517.057432ms)","trace[501015379] 'applied index is now lower than readState.Index' (duration: 634.791146ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:02:39.89319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:38.740266Z","time spent":"1.152861911s","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:02:39.893276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.15196133s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:02:39.893321Z","caller":"traceutil/trace.go:171","msg":"trace[101052259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1258; }","duration":"1.152019111s","start":"2026-05-29T22:02:38.74129Z","end":"2026-05-29T22:02:39.893309Z","steps":["trace[101052259] 'agreement among raft nodes before linearized reading' (duration: 1.151912059s)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:39.89305Z","caller":"traceutil/trace.go:171","msg":"trace[1287708751] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"1.154697664s","start":"2026-05-29T22:02:38.738327Z","end":"2026-05-29T22:02:39.893024Z","steps":["trace[1287708751] 'process raft request' (duration: 1.154550259s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:39.893359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:38.741266Z","time spent":"1.152085273s","remote":"127.0.0.1:36528","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-05-29T22:02:39.893245Z","caller":"traceutil/trace.go:171","msg":"trace[628339445] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"1.145881048s","start":"2026-05-29T22:02:38.747331Z","end":"2026-05-29T22:02:39.893212Z","steps":["trace[628339445] 'process raft request' (duration: 1.145679854s)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:39.892977Z","caller":"traceutil/trace.go:171","msg":"trace[1305079115] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"1.154773666s","start":"2026-05-29T22:02:38.738177Z","end":"2026-05-29T22:02:39.892951Z","steps":["trace[1305079115] 'process raft request' (duration: 1.149332869s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:39.89346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:38.738317Z","time spent":"1.155055583s","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:02:39.893512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:38.747321Z","time spent":"1.146096093s","remote":"127.0.0.1:36798","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-05-29T22:02:39.893659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:38.738163Z","time spent":"1.15539546s","remote":"127.0.0.1:36798","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-05-29T22:02:39.893948Z","caller":"traceutil/trace.go:171","msg":"trace[1859211242] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"606.437445ms","start":"2026-05-29T22:02:39.287493Z","end":"2026-05-29T22:02:39.893931Z","steps":["trace[1859211242] 'process raft request' (duration: 606.135738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:39.894119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:39.287469Z","time spent":"606.553568ms","remote":"127.0.0.1:36798","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-05-29T22:02:50.251059Z","caller":"traceutil/trace.go:171","msg":"trace[1118309695] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"221.962712ms","start":"2026-05-29T22:02:50.029069Z","end":"2026-05-29T22:02:50.251032Z","steps":["trace[1118309695] 'process raft request' (duration: 221.9107ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:50.251124Z","caller":"traceutil/trace.go:171","msg":"trace[330555519] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"222.236647ms","start":"2026-05-29T22:02:50.028829Z","end":"2026-05-29T22:02:50.251066Z","steps":["trace[330555519] 'process raft request' (duration: 221.970831ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:53.081901Z","caller":"traceutil/trace.go:171","msg":"trace[495467769] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"119.421106ms","start":"2026-05-29T22:02:52.962435Z","end":"2026-05-29T22:02:53.081856Z","steps":["trace[495467769] 'process raft request' (duration: 119.001735ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:53.884984Z","caller":"traceutil/trace.go:171","msg":"trace[169044998] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"174.010329ms","start":"2026-05-29T22:02:53.710953Z","end":"2026-05-29T22:02:53.884963Z","steps":["trace[169044998] 'process raft request' (duration: 173.882786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:54.902345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.794439ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T22:02:54.902474Z","caller":"traceutil/trace.go:171","msg":"trace[1210955554] linearizableReadLoop","detail":"{readStateIndex:1358; appliedIndex:1357; }","duration":"633.50959ms","start":"2026-05-29T22:02:54.268939Z","end":"2026-05-29T22:02:54.902449Z","steps":["trace[1210955554] 'read index received' (duration: 48.841µs)","trace[1210955554] 'applied index is now lower than readState.Index' (duration: 633.458789ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:02:54.90253Z","caller":"traceutil/trace.go:171","msg":"trace[1783452178] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"834.179474ms","start":"2026-05-29T22:02:54.068325Z","end":"2026-05-29T22:02:54.902505Z","steps":["trace[1783452178] 'process raft request' (duration: 84.081198ms)","trace[1783452178] 'compare' (duration: 749.159654ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:02:54.902639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:54.068309Z","time spent":"834.270336ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:02:54.902684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.757456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-29T22:02:54.902738Z","caller":"traceutil/trace.go:171","msg":"trace[1548739021] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1304; }","duration":"634.179917ms","start":"2026-05-29T22:02:54.268544Z","end":"2026-05-29T22:02:54.902724Z","steps":["trace[1548739021] 'agreement among raft nodes before linearized reading' (duration: 633.995961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:54.90274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.504103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-29T22:02:54.902738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.057221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T22:02:54.902771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:54.268513Z","time spent":"634.251089ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-29T22:02:54.902784Z","caller":"traceutil/trace.go:171","msg":"trace[1801106573] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1304; }","duration":"629.629826ms","start":"2026-05-29T22:02:54.273141Z","end":"2026-05-29T22:02:54.90277Z","steps":["trace[1801106573] 'agreement among raft nodes before linearized reading' (duration: 629.445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:54.90278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.648872ms","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-05-29T22:02:54.902815Z","caller":"traceutil/trace.go:171","msg":"trace[1218634772] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1304; }","duration":"379.177744ms","start":"2026-05-29T22:02:54.523615Z","end":"2026-05-29T22:02:54.902793Z","steps":["trace[1218634772] 'agreement among raft nodes before linearized reading' (duration: 379.03422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:54.902836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:54.273115Z","time spent":"629.710368ms","remote":"127.0.0.1:36798","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-05-29T22:02:54.902872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:54.523579Z","time spent":"379.282177ms","remote":"127.0.0.1:36528","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-05-29T22:02:54.902867Z","caller":"traceutil/trace.go:171","msg":"trace[1933596824] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1304; }","duration":"536.797115ms","start":"2026-05-29T22:02:54.366048Z","end":"2026-05-29T22:02:54.902845Z","steps":["trace[1933596824] 'agreement among raft nodes before linearized reading' (duration: 536.619071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:54.902932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:54.366028Z","time spent":"536.891448ms","remote":"127.0.0.1:36656","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":"warn","ts":"2026-05-29T22:02:55.522279Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410728836262722,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T22:02:56.100086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.734038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T22:02:56.100289Z","caller":"traceutil/trace.go:171","msg":"trace[1109936167] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"1.191503288s","start":"2026-05-29T22:02:54.908768Z","end":"2026-05-29T22:02:56.100272Z","steps":["trace[1109936167] 'process raft request' (duration: 1.191411356s)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:56.100294Z","caller":"traceutil/trace.go:171","msg":"trace[1659532209] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"1.19365376s","start":"2026-05-29T22:02:54.906607Z","end":"2026-05-29T22:02:56.100261Z","steps":["trace[1659532209] 'process raft request' (duration: 801.676936ms)","trace[1659532209] 'compare' (duration: 391.269647ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:02:56.100359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:54.908749Z","time spent":"1.19157897s","remote":"127.0.0.1:36798","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-05-29T22:02:56.100439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:54.906578Z","time spent":"1.193781223s","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:02:56.100448Z","caller":"traceutil/trace.go:171","msg":"trace[1291432011] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1358; }","duration":"1.078510399s","start":"2026-05-29T22:02:55.021912Z","end":"2026-05-29T22:02:56.100423Z","steps":["trace[1291432011] 'read index received' (duration: 686.411642ms)","trace[1291432011] 'applied index is now lower than readState.Index' (duration: 392.097537ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:02:56.10048Z","caller":"traceutil/trace.go:171","msg":"trace[1901487911] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"1.013073713s","start":"2026-05-29T22:02:55.087373Z","end":"2026-05-29T22:02:56.100446Z","steps":["trace[1901487911] 'process raft request' (duration: 1.012865648s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:56.100599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.078667172s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:02:56.100633Z","caller":"traceutil/trace.go:171","msg":"trace[1094215551] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1307; }","duration":"1.078749465s","start":"2026-05-29T22:02:55.021874Z","end":"2026-05-29T22:02:56.100623Z","steps":["trace[1094215551] 'agreement among raft nodes before linearized reading' (duration: 1.078669553s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:56.100635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:55.087349Z","time spent":"1.013200696s","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:02:56.100666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:55.021858Z","time spent":"1.078799756s","remote":"127.0.0.1:36610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-05-29T22:02:56.941594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.415195ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-29T22:02:56.941708Z","caller":"traceutil/trace.go:171","msg":"trace[659495372] linearizableReadLoop","detail":"{readStateIndex:1362; appliedIndex:1361; }","duration":"841.186165ms","start":"2026-05-29T22:02:56.100504Z","end":"2026-05-29T22:02:56.94169Z","steps":["trace[659495372] 'read index received' (duration: 111.664437ms)","trace[659495372] 'applied index is now lower than readState.Index' (duration: 729.520288ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:02:56.941983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.976257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-29T22:02:56.942032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.053568665s","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-05-29T22:02:56.942059Z","caller":"traceutil/trace.go:171","msg":"trace[360740961] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1307; }","duration":"659.09855ms","start":"2026-05-29T22:02:56.282944Z","end":"2026-05-29T22:02:56.942043Z","steps":["trace[360740961] 'agreement among raft nodes before linearized reading' (duration: 658.980817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:56.94209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.416102625s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T22:02:56.942102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:56.282924Z","time spent":"659.165562ms","remote":"127.0.0.1:36720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":29,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-05-29T22:02:56.9421Z","caller":"traceutil/trace.go:171","msg":"trace[397178261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1307; }","duration":"1.053669668s","start":"2026-05-29T22:02:55.888412Z","end":"2026-05-29T22:02:56.942082Z","steps":["trace[397178261] 'agreement among raft nodes before linearized reading' (duration: 1.053478643s)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:56.942125Z","caller":"traceutil/trace.go:171","msg":"trace[862782298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1307; }","duration":"1.416162156s","start":"2026-05-29T22:02:55.525951Z","end":"2026-05-29T22:02:56.942113Z","steps":["trace[862782298] 'agreement among raft nodes before linearized reading' (duration: 1.416120645s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:56.942154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:55.888392Z","time spent":"1.05374935s","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T22:02:56.94216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:55.525933Z","time spent":"1.416219268s","remote":"127.0.0.1:36528","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-05-29T22:02:56.942048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.551420334s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T22:02:56.942313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.750031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-29T22:02:56.942371Z","caller":"traceutil/trace.go:171","msg":"trace[538322379] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1307; }","duration":"1.551782564s","start":"2026-05-29T22:02:55.390569Z","end":"2026-05-29T22:02:56.942351Z","steps":["trace[538322379] 'agreement among raft nodes before linearized reading' (duration: 1.551424064s)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:56.942387Z","caller":"traceutil/trace.go:171","msg":"trace[1159797793] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1307; }","duration":"872.879114ms","start":"2026-05-29T22:02:56.069491Z","end":"2026-05-29T22:02:56.94237Z","steps":["trace[1159797793] 'agreement among raft nodes before linearized reading' (duration: 872.593037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:56.942411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:55.390548Z","time spent":"1.551848526s","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-05-29T22:02:56.94243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:56.069472Z","time spent":"872.949935ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T22:02:57.262009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.356545ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T22:02:57.262218Z","caller":"traceutil/trace.go:171","msg":"trace[755372732] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1362; }","duration":"312.62434ms","start":"2026-05-29T22:02:56.949582Z","end":"2026-05-29T22:02:57.262206Z","steps":["trace[755372732] 'read index received' (duration: 31.217997ms)","trace[755372732] 'applied index is now lower than readState.Index' (duration: 281.405673ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:02:57.262307Z","caller":"traceutil/trace.go:171","msg":"trace[594363588] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"315.54094ms","start":"2026-05-29T22:02:56.946758Z","end":"2026-05-29T22:02:57.262299Z","steps":["trace[594363588] 'process raft request' (duration: 103.830527ms)","trace[594363588] 'compare' (duration: 211.13068ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:02:57.262351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:56.946725Z","time spent":"315.604332ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:02:57.262484Z","caller":"traceutil/trace.go:171","msg":"trace[1617944859] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"314.967947ms","start":"2026-05-29T22:02:56.947497Z","end":"2026-05-29T22:02:57.262465Z","steps":["trace[1617944859] 'process raft request' (duration: 314.66714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:57.262524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:56.94749Z","time spent":"315.015568ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:02:57.2626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.022219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:02:57.262622Z","caller":"traceutil/trace.go:171","msg":"trace[743172639] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1310; }","duration":"313.059561ms","start":"2026-05-29T22:02:56.949556Z","end":"2026-05-29T22:02:57.262616Z","steps":["trace[743172639] 'agreement among raft nodes before linearized reading' (duration: 313.02557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:57.26264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:56.949546Z","time spent":"313.090731ms","remote":"127.0.0.1:36528","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-05-29T22:02:58.286544Z","caller":"traceutil/trace.go:171","msg":"trace[1770289963] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"180.014845ms","start":"2026-05-29T22:02:58.106499Z","end":"2026-05-29T22:02:58.286514Z","steps":["trace[1770289963] 'process raft request' (duration: 179.81329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:59.733065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.342202962s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T22:02:59.733225Z","caller":"traceutil/trace.go:171","msg":"trace[384203592] linearizableReadLoop","detail":"{readStateIndex:1368; appliedIndex:1366; }","duration":"1.292243821s","start":"2026-05-29T22:02:58.440965Z","end":"2026-05-29T22:02:59.733209Z","steps":["trace[384203592] 'read index received' (duration: 45.111µs)","trace[384203592] 'applied index is now lower than readState.Index' (duration: 1.29219767s)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:02:59.733455Z","caller":"traceutil/trace.go:171","msg":"trace[124545468] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"1.62465488s","start":"2026-05-29T22:02:58.108787Z","end":"2026-05-29T22:02:59.733442Z","steps":["trace[124545468] 'process raft request' (duration: 281.958466ms)","trace[124545468] 'compare' (duration: 1.341461024s)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:02:59.733526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:58.108776Z","time spent":"1.624716631s","remote":"127.0.0.1:36798","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-05-29T22:02:59.733699Z","caller":"traceutil/trace.go:171","msg":"trace[1955702657] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"1.624436615s","start":"2026-05-29T22:02:58.109253Z","end":"2026-05-29T22:02:59.73369Z","steps":["trace[1955702657] 'process raft request' (duration: 1.623898212s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:59.733764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:58.109246Z","time spent":"1.624481686s","remote":"127.0.0.1:36798","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-05-29T22:02:59.733962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.292986758s","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-05-29T22:02:59.734015Z","caller":"traceutil/trace.go:171","msg":"trace[521140805] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1313; }","duration":"1.29307584s","start":"2026-05-29T22:02:58.440924Z","end":"2026-05-29T22:02:59.734Z","steps":["trace[521140805] 'agreement among raft nodes before linearized reading' (duration: 1.292992668s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:59.73406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:58.440905Z","time spent":"1.293141052s","remote":"127.0.0.1:37004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":29,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-05-29T22:02:59.734588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.370605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-29T22:02:59.734671Z","caller":"traceutil/trace.go:171","msg":"trace[554663530] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1313; }","duration":"468.460247ms","start":"2026-05-29T22:02:59.266168Z","end":"2026-05-29T22:02:59.734628Z","steps":["trace[554663530] 'agreement among raft nodes before linearized reading' (duration: 468.13897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:59.734628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.352744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-05-29T22:02:59.734711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:59.266148Z","time spent":"468.54924ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T22:02:59.7348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.331997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:02:59.734851Z","caller":"traceutil/trace.go:171","msg":"trace[1022069276] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1313; }","duration":"584.428959ms","start":"2026-05-29T22:02:59.15041Z","end":"2026-05-29T22:02:59.734839Z","steps":["trace[1022069276] 'agreement among raft nodes before linearized reading' (duration: 584.346058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:59.734897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:59.150392Z","time spent":"584.489821ms","remote":"127.0.0.1:37172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-29T22:02:59.734986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.424344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:72606"} {"level":"info","ts":"2026-05-29T22:02:59.735027Z","caller":"traceutil/trace.go:171","msg":"trace[575202921] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1313; }","duration":"910.67704ms","start":"2026-05-29T22:02:58.82434Z","end":"2026-05-29T22:02:59.735017Z","steps":["trace[575202921] 'agreement among raft nodes before linearized reading' (duration: 910.555457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:59.735063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:58.824319Z","time spent":"910.736112ms","remote":"127.0.0.1:36582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":72628,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-05-29T22:02:59.734729Z","caller":"traceutil/trace.go:171","msg":"trace[444600102] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1313; }","duration":"906.491297ms","start":"2026-05-29T22:02:58.828221Z","end":"2026-05-29T22:02:59.734713Z","steps":["trace[444600102] 'agreement among raft nodes before linearized reading' (duration: 906.339694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:59.735271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:58.828198Z","time spent":"907.052211ms","remote":"127.0.0.1:36594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":140,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-05-29T22:02:59.73552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.205916608s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:02:59.735575Z","caller":"traceutil/trace.go:171","msg":"trace[1615119130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1313; }","duration":"1.20599873s","start":"2026-05-29T22:02:58.529562Z","end":"2026-05-29T22:02:59.73556Z","steps":["trace[1615119130] 'agreement among raft nodes before linearized reading' (duration: 1.205918738s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:59.735616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:58.529545Z","time spent":"1.206060752s","remote":"127.0.0.1:36528","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-05-29T22:02:59.73525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.767855ms","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-05-29T22:02:59.735833Z","caller":"traceutil/trace.go:171","msg":"trace[1418904579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1313; }","duration":"469.505434ms","start":"2026-05-29T22:02:59.266184Z","end":"2026-05-29T22:02:59.73569Z","steps":["trace[1418904579] 'agreement among raft nodes before linearized reading' (duration: 468.108189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:59.73521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.813593ms","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-05-29T22:02:59.735902Z","caller":"traceutil/trace.go:171","msg":"trace[1618387959] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1313; }","duration":"751.54044ms","start":"2026-05-29T22:02:58.984345Z","end":"2026-05-29T22:02:59.735885Z","steps":["trace[1618387959] 'agreement among raft nodes before linearized reading' (duration: 750.773032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:59.735981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:58.984324Z","time spent":"751.635913ms","remote":"127.0.0.1:36798","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-05-29T22:02:59.736174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:59.266164Z","time spent":"469.991375ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T22:03:00.24095Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410728836262746,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T22:03:01.140642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.251223ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T22:03:01.14074Z","caller":"traceutil/trace.go:171","msg":"trace[928423006] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"1.401197192s","start":"2026-05-29T22:02:59.739522Z","end":"2026-05-29T22:03:01.140719Z","steps":["trace[928423006] 'process raft request' (duration: 694.791726ms)","trace[928423006] 'compare' (duration: 705.898984ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:03:01.140809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:59.739509Z","time spent":"1.401272704s","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:03:01.33277Z","caller":"traceutil/trace.go:171","msg":"trace[1808497584] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"1.591954697s","start":"2026-05-29T22:02:59.740793Z","end":"2026-05-29T22:03:01.332748Z","steps":["trace[1808497584] 'process raft request' (duration: 1.591839114s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:01.333012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:59.740775Z","time spent":"1.592154632s","remote":"127.0.0.1:36798","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-05-29T22:03:01.333028Z","caller":"traceutil/trace.go:171","msg":"trace[1223366047] linearizableReadLoop","detail":"{readStateIndex:1372; appliedIndex:1368; }","duration":"1.592340926s","start":"2026-05-29T22:02:59.740658Z","end":"2026-05-29T22:03:01.332999Z","steps":["trace[1223366047] 'read index received' (duration: 693.833801ms)","trace[1223366047] 'applied index is now lower than readState.Index' (duration: 898.506065ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:03:01.333224Z","caller":"traceutil/trace.go:171","msg":"trace[830637255] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"1.275617738s","start":"2026-05-29T22:03:00.05759Z","end":"2026-05-29T22:03:01.333208Z","steps":["trace[830637255] 'process raft request' (duration: 1.275116396s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:01.333362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:00.057567Z","time spent":"1.275701789s","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:03:01.332817Z","caller":"traceutil/trace.go:171","msg":"trace[410536878] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"1.593086565s","start":"2026-05-29T22:02:59.739684Z","end":"2026-05-29T22:03:01.33277Z","steps":["trace[410536878] 'process raft request' (duration: 1.520244569s)","trace[410536878] 'compare' (duration: 72.427816ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:03:01.333701Z","caller":"traceutil/trace.go:171","msg":"trace[801508231] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"1.040580278s","start":"2026-05-29T22:03:00.29311Z","end":"2026-05-29T22:03:01.33369Z","steps":["trace[801508231] 'process raft request' (duration: 1.039772079s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:01.333741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.2194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-29T22:03:01.333767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:00.293081Z","time spent":"1.04065356s","remote":"127.0.0.1:36798","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-05-29T22:03:01.333707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.591945947s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:72606"} {"level":"warn","ts":"2026-05-29T22:03:01.333868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.587908609s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T22:03:01.333875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.593210907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-05-29T22:03:01.333777Z","caller":"traceutil/trace.go:171","msg":"trace[1749607123] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:1318; }","duration":"828.299542ms","start":"2026-05-29T22:03:00.505467Z","end":"2026-05-29T22:03:01.333767Z","steps":["trace[1749607123] 'agreement among raft nodes before linearized reading' (duration: 828.135828ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:01.33389Z","caller":"traceutil/trace.go:171","msg":"trace[959249361] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1318; }","duration":"1.592154351s","start":"2026-05-29T22:02:59.741717Z","end":"2026-05-29T22:03:01.333871Z","steps":["trace[959249361] 'agreement among raft nodes before linearized reading' (duration: 1.591813163s)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:01.333904Z","caller":"traceutil/trace.go:171","msg":"trace[480353774] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1318; }","duration":"1.58795231s","start":"2026-05-29T22:02:59.74594Z","end":"2026-05-29T22:03:01.333892Z","steps":["trace[480353774] 'agreement among raft nodes before linearized reading' (duration: 1.587898329s)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:01.33391Z","caller":"traceutil/trace.go:171","msg":"trace[863877356] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1318; }","duration":"1.593262609s","start":"2026-05-29T22:02:59.740635Z","end":"2026-05-29T22:03:01.333898Z","steps":["trace[863877356] 'agreement among raft nodes before linearized reading' (duration: 1.593159127s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:01.333921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:00.505437Z","time spent":"828.473785ms","remote":"127.0.0.1:36564","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.216\" "} {"level":"warn","ts":"2026-05-29T22:03:01.333937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:59.745935Z","time spent":"1.587995951s","remote":"127.0.0.1:36528","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-05-29T22:03:01.333943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:59.741709Z","time spent":"1.592221763s","remote":"127.0.0.1:36582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":72628,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-05-29T22:03:01.333941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:59.740625Z","time spent":"1.59330761s","remote":"127.0.0.1:36594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":140,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-05-29T22:03:01.333652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.295041248s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T22:03:01.334306Z","caller":"traceutil/trace.go:171","msg":"trace[147437946] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1318; }","duration":"1.295724744s","start":"2026-05-29T22:03:00.038568Z","end":"2026-05-29T22:03:01.334293Z","steps":["trace[147437946] 'agreement among raft nodes before linearized reading' (duration: 1.295039068s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:01.33435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:00.038552Z","time spent":"1.295785866s","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":5,"response size":29,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-29T22:03:01.335281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:59.739665Z","time spent":"1.595536544s","remote":"127.0.0.1:36798","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-05-29T22:03:01.558078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.833111ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T22:03:01.558427Z","caller":"traceutil/trace.go:171","msg":"trace[1275055983] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"151.359129ms","start":"2026-05-29T22:03:01.407035Z","end":"2026-05-29T22:03:01.558394Z","steps":["trace[1275055983] 'process raft request' (duration: 44.12905ms)","trace[1275055983] 'compare' (duration: 106.679936ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:03:02.108055Z","caller":"traceutil/trace.go:171","msg":"trace[537488808] linearizableReadLoop","detail":"{readStateIndex:1378; appliedIndex:1377; }","duration":"188.222054ms","start":"2026-05-29T22:03:01.919811Z","end":"2026-05-29T22:03:02.108033Z","steps":["trace[537488808] 'read index received' (duration: 188.078391ms)","trace[537488808] 'applied index is now lower than readState.Index' (duration: 142.543µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:03:02.108086Z","caller":"traceutil/trace.go:171","msg":"trace[295191131] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"273.40727ms","start":"2026-05-29T22:03:01.834645Z","end":"2026-05-29T22:03:02.108053Z","steps":["trace[295191131] 'process raft request' (duration: 273.269356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:02.108249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.42991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T22:03:02.108304Z","caller":"traceutil/trace.go:171","msg":"trace[1634880782] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1322; }","duration":"188.531591ms","start":"2026-05-29T22:03:01.919761Z","end":"2026-05-29T22:03:02.108293Z","steps":["trace[1634880782] 'agreement among raft nodes before linearized reading' (duration: 188.353077ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:03.330284Z","caller":"traceutil/trace.go:171","msg":"trace[2086158573] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1378; }","duration":"185.770904ms","start":"2026-05-29T22:03:03.14444Z","end":"2026-05-29T22:03:03.330211Z","steps":["trace[2086158573] 'read index received' (duration: 185.650681ms)","trace[2086158573] 'applied index is now lower than readState.Index' (duration: 119.143µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:03:03.330295Z","caller":"traceutil/trace.go:171","msg":"trace[1327138938] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"625.676239ms","start":"2026-05-29T22:03:02.704588Z","end":"2026-05-29T22:03:03.330264Z","steps":["trace[1327138938] 'process raft request' (duration: 625.467734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:03.330468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:02.704569Z","time spent":"625.820543ms","remote":"127.0.0.1:36798","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-05-29T22:03:03.330554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.086581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-29T22:03:03.330647Z","caller":"traceutil/trace.go:171","msg":"trace[47552311] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1323; }","duration":"186.235025ms","start":"2026-05-29T22:03:03.144389Z","end":"2026-05-29T22:03:03.330624Z","steps":["trace[47552311] 'agreement among raft nodes before linearized reading' (duration: 185.942678ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:03.879164Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410728836262775,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T22:03:04.641893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.87212ms","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-05-29T22:03:04.64201Z","caller":"traceutil/trace.go:171","msg":"trace[649320737] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1324; }","duration":"975.999053ms","start":"2026-05-29T22:03:03.665993Z","end":"2026-05-29T22:03:04.641992Z","steps":["trace[649320737] 'range keys from in-memory index tree' (duration: 975.85748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:04.642386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.216931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T22:03:04.64259Z","caller":"traceutil/trace.go:171","msg":"trace[996067098] linearizableReadLoop","detail":"{readStateIndex:1382; appliedIndex:1380; }","duration":"1.264203561s","start":"2026-05-29T22:03:03.37837Z","end":"2026-05-29T22:03:04.642573Z","steps":["trace[996067098] 'read index received' (duration: 946.724454ms)","trace[996067098] 'applied index is now lower than readState.Index' (duration: 317.478377ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:03:04.642671Z","caller":"traceutil/trace.go:171","msg":"trace[1323986483] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"1.260001478s","start":"2026-05-29T22:03:03.38266Z","end":"2026-05-29T22:03:04.642662Z","steps":["trace[1323986483] 'process raft request' (duration: 1.259854045s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:04.642736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:03.382638Z","time spent":"1.26006287s","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:03:04.642776Z","caller":"traceutil/trace.go:171","msg":"trace[1371102043] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"1.302966221s","start":"2026-05-29T22:03:03.339779Z","end":"2026-05-29T22:03:04.642745Z","steps":["trace[1371102043] 'process raft request' (duration: 985.31623ms)","trace[1371102043] 'compare' (duration: 316.698858ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:03:04.642927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:03.339766Z","time spent":"1.303084224s","remote":"127.0.0.1:36798","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-05-29T22:03:04.643081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.303775741s","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-05-29T22:03:04.643123Z","caller":"traceutil/trace.go:171","msg":"trace[2121856083] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1326; }","duration":"1.303831453s","start":"2026-05-29T22:03:03.339281Z","end":"2026-05-29T22:03:04.643112Z","steps":["trace[2121856083] 'agreement among raft nodes before linearized reading' (duration: 1.303622117s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:04.643155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:03.339274Z","time spent":"1.303873134s","remote":"127.0.0.1:36798","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-05-29T22:03:04.643314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.116779037s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:03:04.643349Z","caller":"traceutil/trace.go:171","msg":"trace[1816071779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1326; }","duration":"1.116850348s","start":"2026-05-29T22:03:03.526489Z","end":"2026-05-29T22:03:04.64334Z","steps":["trace[1816071779] 'agreement among raft nodes before linearized reading' (duration: 1.116802097s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:04.643374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:03.526471Z","time spent":"1.11689771s","remote":"127.0.0.1:36528","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-05-29T22:03:04.643408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.150752ms","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-05-29T22:03:04.643458Z","caller":"traceutil/trace.go:171","msg":"trace[1389204201] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1326; }","duration":"878.220553ms","start":"2026-05-29T22:03:03.765226Z","end":"2026-05-29T22:03:04.643446Z","steps":["trace[1389204201] 'agreement among raft nodes before linearized reading' (duration: 878.09362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:04.643496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:03.765219Z","time spent":"878.269395ms","remote":"127.0.0.1:36798","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-05-29T22:03:04.643547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.688924ms","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-05-29T22:03:04.643581Z","caller":"traceutil/trace.go:171","msg":"trace[652833004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1326; }","duration":"878.722195ms","start":"2026-05-29T22:03:03.764851Z","end":"2026-05-29T22:03:04.643573Z","steps":["trace[652833004] 'agreement among raft nodes before linearized reading' (duration: 878.639443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:04.643608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:03.764797Z","time spent":"878.804778ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-29T22:03:05.087796Z","caller":"traceutil/trace.go:171","msg":"trace[271581385] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"440.013368ms","start":"2026-05-29T22:03:04.647758Z","end":"2026-05-29T22:03:05.087771Z","steps":["trace[271581385] 'process raft request' (duration: 439.838874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:05.087961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:04.647732Z","time spent":"440.141012ms","remote":"127.0.0.1:36798","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-05-29T22:03:05.160282Z","caller":"traceutil/trace.go:171","msg":"trace[255419196] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"512.277911ms","start":"2026-05-29T22:03:04.647979Z","end":"2026-05-29T22:03:05.160257Z","steps":["trace[255419196] 'process raft request' (duration: 509.646047ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:05.160523Z","caller":"traceutil/trace.go:171","msg":"trace[1244754418] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"511.819418ms","start":"2026-05-29T22:03:04.648664Z","end":"2026-05-29T22:03:05.160484Z","steps":["trace[1244754418] 'process raft request' (duration: 511.548492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:05.160561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:04.647959Z","time spent":"512.393543ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:03:05.160509Z","caller":"traceutil/trace.go:171","msg":"trace[2139848675] linearizableReadLoop","detail":"{readStateIndex:1385; appliedIndex:1382; }","duration":"507.387212ms","start":"2026-05-29T22:03:04.653024Z","end":"2026-05-29T22:03:05.160411Z","steps":["trace[2139848675] 'read index received' (duration: 434.549096ms)","trace[2139848675] 'applied index is now lower than readState.Index' (duration: 72.836396ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:03:05.16068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.637848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:03:05.161205Z","caller":"traceutil/trace.go:171","msg":"trace[666361202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1329; }","duration":"508.189271ms","start":"2026-05-29T22:03:04.652999Z","end":"2026-05-29T22:03:05.161188Z","steps":["trace[666361202] 'agreement among raft nodes before linearized reading' (duration: 507.624148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:05.161304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.363582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T22:03:05.16141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:04.652991Z","time spent":"508.393107ms","remote":"127.0.0.1:36528","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-05-29T22:03:05.160705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:04.648657Z","time spent":"511.957513ms","remote":"127.0.0.1:36798","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-05-29T22:03:05.161415Z","caller":"traceutil/trace.go:171","msg":"trace[1953494674] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1329; }","duration":"274.547027ms","start":"2026-05-29T22:03:04.886845Z","end":"2026-05-29T22:03:05.161392Z","steps":["trace[1953494674] 'agreement among raft nodes before linearized reading' (duration: 274.336651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:06.487005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104267494s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-29T22:03:06.487088Z","caller":"traceutil/trace.go:171","msg":"trace[1835095300] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1329; }","duration":"1.104393857s","start":"2026-05-29T22:03:05.382674Z","end":"2026-05-29T22:03:06.487068Z","steps":["trace[1835095300] 'range keys from in-memory index tree' (duration: 1.10408903s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:06.487126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:05.382656Z","time spent":"1.104461948s","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-29T22:03:06.495084Z","caller":"traceutil/trace.go:171","msg":"trace[814393056] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"320.062421ms","start":"2026-05-29T22:03:06.174999Z","end":"2026-05-29T22:03:06.495061Z","steps":["trace[814393056] 'process raft request' (duration: 319.913367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:06.495218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:06.174973Z","time spent":"320.172704ms","remote":"127.0.0.1:36694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5500,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:03:08.429418Z","caller":"traceutil/trace.go:171","msg":"trace[281989186] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"214.549901ms","start":"2026-05-29T22:03:08.214841Z","end":"2026-05-29T22:03:08.429391Z","steps":["trace[281989186] 'process raft request' (duration: 214.386417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:14.333754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.737135ms","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-05-29T22:03:14.333838Z","caller":"traceutil/trace.go:171","msg":"trace[504760841] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1393; }","duration":"666.831057ms","start":"2026-05-29T22:03:13.666994Z","end":"2026-05-29T22:03:14.333825Z","steps":["trace[504760841] 'range keys from in-memory index tree' (duration: 666.723585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:14.333967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.236593ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T22:03:14.334102Z","caller":"traceutil/trace.go:171","msg":"trace[1703436908] linearizableReadLoop","detail":"{readStateIndex:1452; appliedIndex:1451; }","duration":"962.779223ms","start":"2026-05-29T22:03:13.371301Z","end":"2026-05-29T22:03:14.33408Z","steps":["trace[1703436908] 'read index received' (duration: 89.341036ms)","trace[1703436908] 'applied index is now lower than readState.Index' (duration: 873.436007ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:03:14.334196Z","caller":"traceutil/trace.go:171","msg":"trace[742667173] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"995.99926ms","start":"2026-05-29T22:03:13.338178Z","end":"2026-05-29T22:03:14.334177Z","steps":["trace[742667173] 'process raft request' (duration: 122.47152ms)","trace[742667173] 'compare' (duration: 872.852594ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:03:14.33431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:13.338166Z","time spent":"996.105592ms","remote":"127.0.0.1:36798","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-05-29T22:03:14.334521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.216773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T22:03:14.334563Z","caller":"traceutil/trace.go:171","msg":"trace[1504088166] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1394; }","duration":"963.294226ms","start":"2026-05-29T22:03:13.371254Z","end":"2026-05-29T22:03:14.334549Z","steps":["trace[1504088166] 'agreement among raft nodes before linearized reading' (duration: 963.182703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:14.3346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:13.371241Z","time spent":"963.348227ms","remote":"127.0.0.1:36926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-05-29T22:03:14.338686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.135835ms","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-05-29T22:03:14.338677Z","caller":"traceutil/trace.go:171","msg":"trace[2069907831] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"879.300229ms","start":"2026-05-29T22:03:13.459335Z","end":"2026-05-29T22:03:14.338635Z","steps":["trace[2069907831] 'process raft request' (duration: 879.117574ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:14.338765Z","caller":"traceutil/trace.go:171","msg":"trace[1996376794] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1395; }","duration":"759.29355ms","start":"2026-05-29T22:03:13.579453Z","end":"2026-05-29T22:03:14.338747Z","steps":["trace[1996376794] 'agreement among raft nodes before linearized reading' (duration: 759.140595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:14.338833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:13.579424Z","time spent":"759.374452ms","remote":"127.0.0.1:36572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-05-29T22:03:14.33889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:13.459308Z","time spent":"879.477174ms","remote":"127.0.0.1:36798","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-05-29T22:03:14.339069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.668523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-05-29T22:03:14.339092Z","caller":"traceutil/trace.go:171","msg":"trace[1208805691] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1395; }","duration":"171.752685ms","start":"2026-05-29T22:03:14.167333Z","end":"2026-05-29T22:03:14.339086Z","steps":["trace[1208805691] 'agreement among raft nodes before linearized reading' (duration: 171.640372ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:14.801701Z","caller":"traceutil/trace.go:171","msg":"trace[813898698] linearizableReadLoop","detail":"{readStateIndex:1456; appliedIndex:1453; }","duration":"374.348187ms","start":"2026-05-29T22:03:14.427327Z","end":"2026-05-29T22:03:14.801676Z","steps":["trace[813898698] 'read index received' (duration: 368.894555ms)","trace[813898698] 'applied index is now lower than readState.Index' (duration: 5.452622ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:03:14.801832Z","caller":"traceutil/trace.go:171","msg":"trace[1609750147] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"458.6336ms","start":"2026-05-29T22:03:14.343187Z","end":"2026-05-29T22:03:14.80182Z","steps":["trace[1609750147] 'process raft request' (duration: 458.415525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:14.802113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:14.343173Z","time spent":"458.863985ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:03:14.80237Z","caller":"traceutil/trace.go:171","msg":"trace[766944103] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"105.668062ms","start":"2026-05-29T22:03:14.696691Z","end":"2026-05-29T22:03:14.802359Z","steps":["trace[766944103] 'process raft request' (duration: 104.950715ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:14.802514Z","caller":"traceutil/trace.go:171","msg":"trace[515919068] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"461.243514ms","start":"2026-05-29T22:03:14.34124Z","end":"2026-05-29T22:03:14.802483Z","steps":["trace[515919068] 'process raft request' (duration: 454.968892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:14.802601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.272578ms","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-05-29T22:03:14.80264Z","caller":"traceutil/trace.go:171","msg":"trace[158286775] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1399; }","duration":"375.36ms","start":"2026-05-29T22:03:14.427268Z","end":"2026-05-29T22:03:14.802628Z","steps":["trace[158286775] 'agreement among raft nodes before linearized reading' (duration: 375.246278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:14.802642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:14.34122Z","time spent":"461.369116ms","remote":"127.0.0.1:36596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":929,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T22:03:14.802671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:14.427244Z","time spent":"375.418422ms","remote":"127.0.0.1:37200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-29T22:03:14.802656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.998156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:03:14.802767Z","caller":"traceutil/trace.go:171","msg":"trace[1604081336] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1399; }","duration":"277.133769ms","start":"2026-05-29T22:03:14.525576Z","end":"2026-05-29T22:03:14.80271Z","steps":["trace[1604081336] 'agreement among raft nodes before linearized reading' (duration: 277.002866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:14.803043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.839384ms","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-05-29T22:03:14.8031Z","caller":"traceutil/trace.go:171","msg":"trace[1252088229] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1399; }","duration":"180.936256ms","start":"2026-05-29T22:03:14.622149Z","end":"2026-05-29T22:03:14.803085Z","steps":["trace[1252088229] 'agreement among raft nodes before linearized reading' (duration: 180.776893ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:14.801749Z","caller":"traceutil/trace.go:171","msg":"trace[1766214678] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"459.797578ms","start":"2026-05-29T22:03:14.341923Z","end":"2026-05-29T22:03:14.801721Z","steps":["trace[1766214678] 'process raft request' (duration: 459.597963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:14.803704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:14.341896Z","time spent":"461.657733ms","remote":"127.0.0.1:36798","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-05-29T22:03:15.599264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.631964ms","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-05-29T22:03:15.599342Z","caller":"traceutil/trace.go:171","msg":"trace[2035806121] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1402; }","duration":"292.759648ms","start":"2026-05-29T22:03:15.306565Z","end":"2026-05-29T22:03:15.599324Z","steps":["trace[2035806121] 'range keys from in-memory index tree' (duration: 292.43156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:15.599355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.638631ms","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-05-29T22:03:15.599406Z","caller":"traceutil/trace.go:171","msg":"trace[377667004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1402; }","duration":"260.720802ms","start":"2026-05-29T22:03:15.338672Z","end":"2026-05-29T22:03:15.599393Z","steps":["trace[377667004] 'range keys from in-memory index tree' (duration: 260.500696ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:17.087336Z","caller":"traceutil/trace.go:171","msg":"trace[2087442479] linearizableReadLoop","detail":"{readStateIndex:1470; appliedIndex:1469; }","duration":"203.788861ms","start":"2026-05-29T22:03:16.883517Z","end":"2026-05-29T22:03:17.087306Z","steps":["trace[2087442479] 'read index received' (duration: 132.681957ms)","trace[2087442479] 'applied index is now lower than readState.Index' (duration: 71.104654ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:03:17.08751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.982866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:03:17.087566Z","caller":"traceutil/trace.go:171","msg":"trace[1632514253] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1411; }","duration":"204.103039ms","start":"2026-05-29T22:03:16.883452Z","end":"2026-05-29T22:03:17.087555Z","steps":["trace[1632514253] 'agreement among raft nodes before linearized reading' (duration: 203.958416ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:17.087773Z","caller":"traceutil/trace.go:171","msg":"trace[139131373] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"234.193878ms","start":"2026-05-29T22:03:16.853566Z","end":"2026-05-29T22:03:17.08776Z","steps":["trace[139131373] 'process raft request' (duration: 162.595592ms)","trace[139131373] 'compare' (duration: 70.735686ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:03:17.342254Z","caller":"traceutil/trace.go:171","msg":"trace[1999656079] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"250.72524ms","start":"2026-05-29T22:03:17.091442Z","end":"2026-05-29T22:03:17.342168Z","steps":["trace[1999656079] 'process raft request' (duration: 250.584256ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:17.343845Z","caller":"traceutil/trace.go:171","msg":"trace[290346404] linearizableReadLoop","detail":"{readStateIndex:1472; appliedIndex:1470; }","duration":"226.942892ms","start":"2026-05-29T22:03:17.11688Z","end":"2026-05-29T22:03:17.343823Z","steps":["trace[290346404] 'read index received' (duration: 225.106668ms)","trace[290346404] 'applied index is now lower than readState.Index' (duration: 1.834834ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:03:17.344034Z","caller":"traceutil/trace.go:171","msg":"trace[112159855] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"251.372794ms","start":"2026-05-29T22:03:17.092646Z","end":"2026-05-29T22:03:17.344019Z","steps":["trace[112159855] 'process raft request' (duration: 251.048346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:17.344102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.208459ms","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":"info","ts":"2026-05-29T22:03:17.344158Z","caller":"traceutil/trace.go:171","msg":"trace[940805424] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1413; }","duration":"227.337322ms","start":"2026-05-29T22:03:17.116807Z","end":"2026-05-29T22:03:17.344145Z","steps":["trace[940805424] 'agreement among raft nodes before linearized reading' (duration: 227.155567ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:55.097728Z","caller":"traceutil/trace.go:171","msg":"trace[129877662] transaction","detail":"{read_only:false; response_revision:1601; number_of_response:1; }","duration":"100.248528ms","start":"2026-05-29T22:03:54.997453Z","end":"2026-05-29T22:03:55.097702Z","steps":["trace[129877662] 'process raft request' (duration: 100.203947ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:55.097821Z","caller":"traceutil/trace.go:171","msg":"trace[1712678975] transaction","detail":"{read_only:false; response_revision:1600; number_of_response:1; }","duration":"100.716289ms","start":"2026-05-29T22:03:54.997034Z","end":"2026-05-29T22:03:55.097751Z","steps":["trace[1712678975] 'process raft request' (duration: 53.760523ms)","trace[1712678975] 'compare' (duration: 46.764621ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:03:56.029634Z","caller":"traceutil/trace.go:171","msg":"trace[2058846176] transaction","detail":"{read_only:false; response_revision:1606; number_of_response:1; }","duration":"107.938677ms","start":"2026-05-29T22:03:55.921666Z","end":"2026-05-29T22:03:56.029604Z","steps":["trace[2058846176] 'process raft request' (duration: 98.69188ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:05:36.858307Z","caller":"traceutil/trace.go:171","msg":"trace[1070643160] transaction","detail":"{read_only:false; response_revision:2067; number_of_response:1; }","duration":"144.74179ms","start":"2026-05-29T22:05:36.71354Z","end":"2026-05-29T22:05:36.858282Z","steps":["trace[1070643160] 'process raft request' (duration: 144.547775ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:06:53.678568Z","caller":"traceutil/trace.go:171","msg":"trace[1375253296] transaction","detail":"{read_only:false; response_revision:2439; number_of_response:1; }","duration":"127.587641ms","start":"2026-05-29T22:06:53.550954Z","end":"2026-05-29T22:06:53.678541Z","steps":["trace[1375253296] 'process raft request' (duration: 127.380496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:07:02.862368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.678464ms","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-05-29T22:07:02.862462Z","caller":"traceutil/trace.go:171","msg":"trace[1361734146] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2486; }","duration":"108.819358ms","start":"2026-05-29T22:07:02.753621Z","end":"2026-05-29T22:07:02.86244Z","steps":["trace[1361734146] 'range keys from in-memory index tree' (duration: 108.462039ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:07:03.808806Z","caller":"traceutil/trace.go:171","msg":"trace[1981930167] transaction","detail":"{read_only:false; response_revision:2491; number_of_response:1; }","duration":"100.515287ms","start":"2026-05-29T22:07:03.708234Z","end":"2026-05-29T22:07:03.808749Z","steps":["trace[1981930167] 'process raft request' (duration: 100.370374ms)"],"step_count":1}