{"level":"warn","ts":"2026-07-17T15:58:05.125607Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T15:58:05.125729Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.207: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.19.213.207:2380","--initial-cluster=instance=https://199.19.213.207:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.207:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.207: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-07-17T15:58:05.125822Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T15:58:05.125831Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.207:2380"]} {"level":"info","ts":"2026-07-17T15:58:05.125853Z","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-07-17T15:58:05.126816Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.207:2379"]} {"level":"info","ts":"2026-07-17T15:58:05.126904Z","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.19.213.207:2380"],"listen-peer-urls":["https://199.19.213.207:2380"],"advertise-client-urls":["https://199.19.213.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.207: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-07-17T15:58:05.132194Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.022023ms"} {"level":"info","ts":"2026-07-17T15:58:05.141117Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"520ed656383c33a4","cluster-id":"21f34f1629612ac4"} {"level":"info","ts":"2026-07-17T15:58:05.141247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 switched to configuration voters=()"} {"level":"info","ts":"2026-07-17T15:58:05.141283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 became follower at term 0"} {"level":"info","ts":"2026-07-17T15:58:05.141302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 520ed656383c33a4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-17T15:58:05.141312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 became follower at term 1"} {"level":"info","ts":"2026-07-17T15:58:05.141377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 switched to configuration voters=(5912899026583040932)"} {"level":"warn","ts":"2026-07-17T15:58:05.153737Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-17T15:58:05.156002Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-17T15:58:05.158225Z","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-07-17T15:58:05.16084Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"520ed656383c33a4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-17T15:58:05.161166Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"520ed656383c33a4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-17T15:58:05.161395Z","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-07-17T15:58:05.161512Z","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-07-17T15:58:05.161526Z","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-07-17T15:58:05.164439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 switched to configuration voters=(5912899026583040932)"} {"level":"info","ts":"2026-07-17T15:58:05.165084Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"21f34f1629612ac4","local-member-id":"520ed656383c33a4","added-peer-id":"520ed656383c33a4","added-peer-peer-urls":["https://199.19.213.207:2380"]} {"level":"info","ts":"2026-07-17T15:58:05.170429Z","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-07-17T15:58:05.170532Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.207:2380"} {"level":"info","ts":"2026-07-17T15:58:05.170596Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.207:2380"} {"level":"info","ts":"2026-07-17T15:58:05.170789Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"520ed656383c33a4","initial-advertise-peer-urls":["https://199.19.213.207:2380"],"listen-peer-urls":["https://199.19.213.207:2380"],"advertise-client-urls":["https://199.19.213.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-17T15:58:05.17083Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-17T15:58:05.242075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 is starting a new election at term 1"} {"level":"info","ts":"2026-07-17T15:58:05.242124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-17T15:58:05.242149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 received MsgPreVoteResp from 520ed656383c33a4 at term 1"} {"level":"info","ts":"2026-07-17T15:58:05.242162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 became candidate at term 2"} {"level":"info","ts":"2026-07-17T15:58:05.242169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 received MsgVoteResp from 520ed656383c33a4 at term 2"} {"level":"info","ts":"2026-07-17T15:58:05.24218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 became leader at term 2"} {"level":"info","ts":"2026-07-17T15:58:05.242189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 520ed656383c33a4 elected leader 520ed656383c33a4 at term 2"} {"level":"info","ts":"2026-07-17T15:58:05.242865Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"520ed656383c33a4","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.207:2379]}","request-path":"/0/members/520ed656383c33a4/attributes","cluster-id":"21f34f1629612ac4","publish-timeout":"7s"} {"level":"info","ts":"2026-07-17T15:58:05.242892Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T15:58:05.243025Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T15:58:05.243075Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T15:58:05.24325Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-17T15:58:05.2434Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-17T15:58:05.243675Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"21f34f1629612ac4","local-member-id":"520ed656383c33a4","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T15:58:05.243796Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T15:58:05.243839Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T15:58:05.247112Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.207:2379"} {"level":"info","ts":"2026-07-17T15:58:05.250633Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-17T15:58:29.802436Z","caller":"traceutil/trace.go:171","msg":"trace[967835020] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"217.511594ms","start":"2026-07-17T15:58:29.5849Z","end":"2026-07-17T15:58:29.802412Z","steps":["trace[967835020] 'process raft request' (duration: 217.37501ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:29.802794Z","caller":"traceutil/trace.go:171","msg":"trace[933453668] linearizableReadLoop","detail":"{readStateIndex:448; appliedIndex:448; }","duration":"167.806716ms","start":"2026-07-17T15:58:29.634967Z","end":"2026-07-17T15:58:29.802773Z","steps":["trace[933453668] 'read index received' (duration: 167.800826ms)","trace[933453668] 'applied index is now lower than readState.Index' (duration: 4.72µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T15:58:29.802946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.914599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-07-17T15:58:29.803246Z","caller":"traceutil/trace.go:171","msg":"trace[1415978357] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:433; }","duration":"168.263859ms","start":"2026-07-17T15:58:29.634962Z","end":"2026-07-17T15:58:29.803226Z","steps":["trace[1415978357] 'agreement among raft nodes before linearized reading' (duration: 167.874748ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:35.607819Z","caller":"traceutil/trace.go:171","msg":"trace[1425218805] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:488; }","duration":"122.351977ms","start":"2026-07-17T15:58:35.485448Z","end":"2026-07-17T15:58:35.6078Z","steps":["trace[1425218805] 'read index received' (duration: 122.181742ms)","trace[1425218805] 'applied index is now lower than readState.Index' (duration: 169.435µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T15:58:35.607894Z","caller":"traceutil/trace.go:171","msg":"trace[706750662] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"202.971092ms","start":"2026-07-17T15:58:35.404907Z","end":"2026-07-17T15:58:35.607878Z","steps":["trace[706750662] 'process raft request' (duration: 202.785186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:35.607923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.46526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-17T15:58:35.607945Z","caller":"traceutil/trace.go:171","msg":"trace[1752189766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:473; }","duration":"122.529482ms","start":"2026-07-17T15:58:35.485409Z","end":"2026-07-17T15:58:35.607939Z","steps":["trace[1752189766] 'agreement among raft nodes before linearized reading' (duration: 122.474301ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:42.045969Z","caller":"traceutil/trace.go:171","msg":"trace[1242281702] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"108.042431ms","start":"2026-07-17T15:58:41.937865Z","end":"2026-07-17T15:58:42.045907Z","steps":["trace[1242281702] 'process raft request' (duration: 79.599075ms)","trace[1242281702] 'compare' (duration: 28.020504ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T15:58:42.210684Z","caller":"traceutil/trace.go:171","msg":"trace[9017451] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"100.728945ms","start":"2026-07-17T15:58:42.109923Z","end":"2026-07-17T15:58:42.210652Z","steps":["trace[9017451] 'process raft request' (duration: 89.380603ms)","trace[9017451] 'compare' (duration: 11.176057ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T15:58:42.459271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.809681ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T15:58:42.459385Z","caller":"traceutil/trace.go:171","msg":"trace[363746710] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"154.759246ms","start":"2026-07-17T15:58:42.304595Z","end":"2026-07-17T15:58:42.459355Z","steps":["trace[363746710] 'process raft request' (duration: 14.375608ms)","trace[363746710] 'compare' (duration: 139.628906ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T15:58:42.462533Z","caller":"traceutil/trace.go:171","msg":"trace[1558598459] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"157.908515ms","start":"2026-07-17T15:58:42.304599Z","end":"2026-07-17T15:58:42.462508Z","steps":["trace[1558598459] 'process raft request' (duration: 157.693339ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:42.462732Z","caller":"traceutil/trace.go:171","msg":"trace[1953141104] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"157.597396ms","start":"2026-07-17T15:58:42.305122Z","end":"2026-07-17T15:58:42.462719Z","steps":["trace[1953141104] 'process raft request' (duration: 157.338899ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:42.462965Z","caller":"traceutil/trace.go:171","msg":"trace[1275726782] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"157.440101ms","start":"2026-07-17T15:58:42.305509Z","end":"2026-07-17T15:58:42.462949Z","steps":["trace[1275726782] 'process raft request' (duration: 157.143073ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:42.463105Z","caller":"traceutil/trace.go:171","msg":"trace[1677091709] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"156.880075ms","start":"2026-07-17T15:58:42.306202Z","end":"2026-07-17T15:58:42.463082Z","steps":["trace[1677091709] 'process raft request' (duration: 156.71266ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:42.463235Z","caller":"traceutil/trace.go:171","msg":"trace[1301769567] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:526; }","duration":"158.263235ms","start":"2026-07-17T15:58:42.304952Z","end":"2026-07-17T15:58:42.463215Z","steps":["trace[1301769567] 'read index received' (duration: 14.063089ms)","trace[1301769567] 'applied index is now lower than readState.Index' (duration: 144.199006ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T15:58:42.463428Z","caller":"traceutil/trace.go:171","msg":"trace[103070273] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"157.148693ms","start":"2026-07-17T15:58:42.306264Z","end":"2026-07-17T15:58:42.463413Z","steps":["trace[103070273] 'process raft request' (duration: 156.759372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:42.463503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.742668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-07-17T15:58:42.463607Z","caller":"traceutil/trace.go:171","msg":"trace[152225348] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:515; }","duration":"158.895032ms","start":"2026-07-17T15:58:42.304683Z","end":"2026-07-17T15:58:42.463578Z","steps":["trace[152225348] 'agreement among raft nodes before linearized reading' (duration: 158.631815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:42.463634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.657714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329956"} {"level":"info","ts":"2026-07-17T15:58:42.463696Z","caller":"traceutil/trace.go:171","msg":"trace[486046754] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:515; }","duration":"123.780497ms","start":"2026-07-17T15:58:42.339899Z","end":"2026-07-17T15:58:42.46368Z","steps":["trace[486046754] 'agreement among raft nodes before linearized reading' (duration: 123.421597ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:43.872689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.1154ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-07-17T15:58:44.351612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.853811ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T15:58:44.351857Z","caller":"traceutil/trace.go:171","msg":"trace[244739438] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"242.4477ms","start":"2026-07-17T15:58:44.109373Z","end":"2026-07-17T15:58:44.351821Z","steps":["trace[244739438] 'process raft request' (duration: 137.320052ms)","trace[244739438] 'compare' (duration: 104.672775ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T15:58:44.352091Z","caller":"traceutil/trace.go:171","msg":"trace[217914454] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"234.781082ms","start":"2026-07-17T15:58:44.117291Z","end":"2026-07-17T15:58:44.352072Z","steps":["trace[217914454] 'process raft request' (duration: 234.442703ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:44.35211Z","caller":"traceutil/trace.go:171","msg":"trace[185143002] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"215.468066ms","start":"2026-07-17T15:58:44.136622Z","end":"2026-07-17T15:58:44.35209Z","steps":["trace[185143002] 'process raft request' (duration: 215.384613ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:44.352083Z","caller":"traceutil/trace.go:171","msg":"trace[1395186066] transaction","detail":"{read_only:false; number_of_response:1; response_revision:539; }","duration":"234.67951ms","start":"2026-07-17T15:58:44.117371Z","end":"2026-07-17T15:58:44.35205Z","steps":["trace[1395186066] 'process raft request' (duration: 234.442843ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:44.352222Z","caller":"traceutil/trace.go:171","msg":"trace[1790689073] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"234.7042ms","start":"2026-07-17T15:58:44.117508Z","end":"2026-07-17T15:58:44.352213Z","steps":["trace[1790689073] 'process raft request' (duration: 234.34468ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:44.404817Z","caller":"traceutil/trace.go:171","msg":"trace[274297376] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"239.564448ms","start":"2026-07-17T15:58:44.165219Z","end":"2026-07-17T15:58:44.404784Z","steps":["trace[274297376] 'process raft request' (duration: 239.390173ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:44.404838Z","caller":"traceutil/trace.go:171","msg":"trace[2041310575] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"239.538668ms","start":"2026-07-17T15:58:44.165279Z","end":"2026-07-17T15:58:44.404817Z","steps":["trace[2041310575] 'process raft request' (duration: 239.439075ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:44.405248Z","caller":"traceutil/trace.go:171","msg":"trace[513172832] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"236.882943ms","start":"2026-07-17T15:58:44.168345Z","end":"2026-07-17T15:58:44.405228Z","steps":["trace[513172832] 'process raft request' (duration: 236.601105ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:44.40532Z","caller":"traceutil/trace.go:171","msg":"trace[1576552650] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"232.376505ms","start":"2026-07-17T15:58:44.172922Z","end":"2026-07-17T15:58:44.405299Z","steps":["trace[1576552650] 'process raft request' (duration: 232.315883ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:44.405402Z","caller":"traceutil/trace.go:171","msg":"trace[129041432] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"237.189771ms","start":"2026-07-17T15:58:44.168197Z","end":"2026-07-17T15:58:44.405386Z","steps":["trace[129041432] 'process raft request' (duration: 236.585474ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:44.405452Z","caller":"traceutil/trace.go:171","msg":"trace[1839598806] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"234.204317ms","start":"2026-07-17T15:58:44.171236Z","end":"2026-07-17T15:58:44.405441Z","steps":["trace[1839598806] 'process raft request' (duration: 233.842387ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:44.405424Z","caller":"traceutil/trace.go:171","msg":"trace[1839499819] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"235.469473ms","start":"2026-07-17T15:58:44.169927Z","end":"2026-07-17T15:58:44.405396Z","steps":["trace[1839499819] 'process raft request' (duration: 235.078201ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:44.40547Z","caller":"traceutil/trace.go:171","msg":"trace[915327176] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"240.043142ms","start":"2026-07-17T15:58:44.165381Z","end":"2026-07-17T15:58:44.405424Z","steps":["trace[915327176] 'process raft request' (duration: 239.367763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:44.736371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.945894ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T15:58:44.736571Z","caller":"traceutil/trace.go:171","msg":"trace[1439475201] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"478.219159ms","start":"2026-07-17T15:58:44.258336Z","end":"2026-07-17T15:58:44.736555Z","steps":["trace[1439475201] 'process raft request' (duration: 260.029648ms)","trace[1439475201] 'compare' (duration: 217.731628ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T15:58:44.736627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:44.258309Z","time spent":"478.293772ms","remote":"127.0.0.1:49072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T15:58:44.736704Z","caller":"traceutil/trace.go:171","msg":"trace[1567037989] transaction","detail":"{read_only:false; number_of_response:0; response_revision:550; }","duration":"380.133191ms","start":"2026-07-17T15:58:44.356512Z","end":"2026-07-17T15:58:44.736645Z","steps":["trace[1567037989] 'process raft request' (duration: 379.941665ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:44.736769Z","caller":"traceutil/trace.go:171","msg":"trace[27564386] transaction","detail":"{read_only:false; number_of_response:1; response_revision:550; }","duration":"371.626289ms","start":"2026-07-17T15:58:44.365128Z","end":"2026-07-17T15:58:44.736755Z","steps":["trace[27564386] 'process raft request' (duration: 371.379602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:44.737219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:44.356489Z","time spent":"380.292476ms","remote":"127.0.0.1:49618","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T15:58:44.737278Z","caller":"traceutil/trace.go:171","msg":"trace[580937231] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"368.622984ms","start":"2026-07-17T15:58:44.368637Z","end":"2026-07-17T15:58:44.73726Z","steps":["trace[580937231] 'process raft request' (duration: 367.972866ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:44.737548Z","caller":"traceutil/trace.go:171","msg":"trace[1149596498] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"353.100325ms","start":"2026-07-17T15:58:44.38442Z","end":"2026-07-17T15:58:44.737521Z","steps":["trace[1149596498] 'process raft request' (duration: 352.338803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:44.737569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:44.36511Z","time spent":"371.688341ms","remote":"127.0.0.1:49072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10111,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T15:58:44.737716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:44.384406Z","time spent":"353.175226ms","remote":"127.0.0.1:49072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T15:58:44.737997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:44.36863Z","time spent":"368.696297ms","remote":"127.0.0.1:49072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7326,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T15:58:44.768076Z","caller":"traceutil/trace.go:171","msg":"trace[433013870] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"344.928803ms","start":"2026-07-17T15:58:44.423123Z","end":"2026-07-17T15:58:44.768052Z","steps":["trace[433013870] 'process raft request' (duration: 344.738138ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:44.768116Z","caller":"traceutil/trace.go:171","msg":"trace[1350946148] transaction","detail":"{read_only:false; number_of_response:1; response_revision:552; }","duration":"352.656422ms","start":"2026-07-17T15:58:44.415375Z","end":"2026-07-17T15:58:44.768031Z","steps":["trace[1350946148] 'process raft request' (duration: 352.380164ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:44.768222Z","caller":"traceutil/trace.go:171","msg":"trace[2044387088] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"321.155759ms","start":"2026-07-17T15:58:44.447055Z","end":"2026-07-17T15:58:44.76821Z","steps":["trace[2044387088] 'process raft request' (duration: 320.882182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:44.768234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:44.423098Z","time spent":"345.054567ms","remote":"127.0.0.1:49072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T15:58:44.768311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:44.447036Z","time spent":"321.213992ms","remote":"127.0.0.1:49072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T15:58:44.768306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:44.41535Z","time spent":"352.844988ms","remote":"127.0.0.1:49072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7374,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T15:58:45.036003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.308573ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T15:58:45.036104Z","caller":"traceutil/trace.go:171","msg":"trace[1550696955] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"286.50967ms","start":"2026-07-17T15:58:44.749579Z","end":"2026-07-17T15:58:45.036089Z","steps":["trace[1550696955] 'process raft request' (duration: 126.039392ms)","trace[1550696955] 'compare' (duration: 160.17868ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T15:58:45.047Z","caller":"traceutil/trace.go:171","msg":"trace[1839012857] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:577; }","duration":"114.293179ms","start":"2026-07-17T15:58:44.932689Z","end":"2026-07-17T15:58:45.046982Z","steps":["trace[1839012857] 'read index received' (duration: 113.995631ms)","trace[1839012857] 'applied index is now lower than readState.Index' (duration: 296.778µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T15:58:45.047133Z","caller":"traceutil/trace.go:171","msg":"trace[934957412] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"246.396584ms","start":"2026-07-17T15:58:44.800685Z","end":"2026-07-17T15:58:45.047082Z","steps":["trace[934957412] 'process raft request' (duration: 246.112326ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:45.047204Z","caller":"traceutil/trace.go:171","msg":"trace[85989754] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"244.16325ms","start":"2026-07-17T15:58:44.803028Z","end":"2026-07-17T15:58:45.047192Z","steps":["trace[85989754] 'process raft request' (duration: 243.921563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:45.047288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.593128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-17T15:58:45.047102Z","caller":"traceutil/trace.go:171","msg":"trace[667815291] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"270.435445ms","start":"2026-07-17T15:58:44.776644Z","end":"2026-07-17T15:58:45.04708Z","steps":["trace[667815291] 'process raft request' (duration: 269.995982ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:45.04732Z","caller":"traceutil/trace.go:171","msg":"trace[710958440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:558; }","duration":"114.679371ms","start":"2026-07-17T15:58:44.932633Z","end":"2026-07-17T15:58:45.047312Z","steps":["trace[710958440] 'agreement among raft nodes before linearized reading' (duration: 114.424543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:45.491959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.797963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T15:58:45.492077Z","caller":"traceutil/trace.go:171","msg":"trace[2131991110] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"363.558471ms","start":"2026-07-17T15:58:45.1285Z","end":"2026-07-17T15:58:45.492059Z","steps":["trace[2131991110] 'process raft request' (duration: 110.586923ms)","trace[2131991110] 'compare' (duration: 252.67534ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T15:58:45.492138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:45.128471Z","time spent":"363.639203ms","remote":"127.0.0.1:49072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T15:58:45.49854Z","caller":"traceutil/trace.go:171","msg":"trace[1859584696] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"363.038057ms","start":"2026-07-17T15:58:45.13549Z","end":"2026-07-17T15:58:45.498528Z","steps":["trace[1859584696] 'process raft request' (duration: 362.918284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:45.498608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:45.135477Z","time spent":"363.094689ms","remote":"127.0.0.1:49072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T15:58:45.498746Z","caller":"traceutil/trace.go:171","msg":"trace[866048798] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"366.526476ms","start":"2026-07-17T15:58:45.132212Z","end":"2026-07-17T15:58:45.498738Z","steps":["trace[866048798] 'process raft request' (duration: 366.066553ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:45.498792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:45.132189Z","time spent":"366.581057ms","remote":"127.0.0.1:49072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T15:58:45.848517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.496411ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T15:58:45.848616Z","caller":"traceutil/trace.go:171","msg":"trace[1320677736] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"341.815638ms","start":"2026-07-17T15:58:45.506786Z","end":"2026-07-17T15:58:45.848602Z","steps":["trace[1320677736] 'process raft request' (duration: 188.168023ms)","trace[1320677736] 'compare' (duration: 153.380868ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T15:58:45.84866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:45.506761Z","time spent":"341.879709ms","remote":"127.0.0.1:49072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T15:58:45.869439Z","caller":"traceutil/trace.go:171","msg":"trace[281866199] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:587; }","duration":"205.583088ms","start":"2026-07-17T15:58:45.663827Z","end":"2026-07-17T15:58:45.86941Z","steps":["trace[281866199] 'read index received' (duration: 31.194475ms)","trace[281866199] 'applied index is now lower than readState.Index' (duration: 174.387072ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T15:58:45.869535Z","caller":"traceutil/trace.go:171","msg":"trace[731479841] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"318.532287ms","start":"2026-07-17T15:58:45.550942Z","end":"2026-07-17T15:58:45.869474Z","steps":["trace[731479841] 'process raft request' (duration: 318.188028ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:45.869555Z","caller":"traceutil/trace.go:171","msg":"trace[1106997547] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"359.682754ms","start":"2026-07-17T15:58:45.509798Z","end":"2026-07-17T15:58:45.869481Z","steps":["trace[1106997547] 'process raft request' (duration: 359.113197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:45.869851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:45.509734Z","time spent":"360.015633ms","remote":"127.0.0.1:49072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T15:58:45.86968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.840075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T15:58:45.870027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:45.550921Z","time spent":"318.676732ms","remote":"127.0.0.1:49072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T15:58:45.870151Z","caller":"traceutil/trace.go:171","msg":"trace[401927888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"206.269167ms","start":"2026-07-17T15:58:45.663778Z","end":"2026-07-17T15:58:45.870047Z","steps":["trace[401927888] 'agreement among raft nodes before linearized reading' (duration: 205.829144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:45.929368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.600445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-17T15:58:45.929455Z","caller":"traceutil/trace.go:171","msg":"trace[102022881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:569; }","duration":"170.726039ms","start":"2026-07-17T15:58:45.75871Z","end":"2026-07-17T15:58:45.929436Z","steps":["trace[102022881] 'agreement among raft nodes before linearized reading' (duration: 170.595495ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:45.929438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.699404ms","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-07-17T15:58:45.929599Z","caller":"traceutil/trace.go:171","msg":"trace[1904938711] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:569; }","duration":"257.875129ms","start":"2026-07-17T15:58:45.671704Z","end":"2026-07-17T15:58:45.929579Z","steps":["trace[1904938711] 'agreement among raft nodes before linearized reading' (duration: 257.622281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:46.303963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.762648ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T15:58:46.304202Z","caller":"traceutil/trace.go:171","msg":"trace[1785571584] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"374.871153ms","start":"2026-07-17T15:58:45.929275Z","end":"2026-07-17T15:58:46.304146Z","steps":["trace[1785571584] 'read index received' (duration: 160.870588ms)","trace[1785571584] 'applied index is now lower than readState.Index' (duration: 213.998725ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T15:58:46.30421Z","caller":"traceutil/trace.go:171","msg":"trace[1020064233] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"420.337412ms","start":"2026-07-17T15:58:45.883837Z","end":"2026-07-17T15:58:46.304175Z","steps":["trace[1020064233] 'process raft request' (duration: 206.277825ms)","trace[1020064233] 'compare' (duration: 213.604854ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T15:58:46.304602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.287298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T15:58:46.304668Z","caller":"traceutil/trace.go:171","msg":"trace[1018278326] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:570; }","duration":"433.391081ms","start":"2026-07-17T15:58:45.871259Z","end":"2026-07-17T15:58:46.30465Z","steps":["trace[1018278326] 'agreement among raft nodes before linearized reading' (duration: 433.00876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:46.30471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:45.871226Z","time spent":"433.471953ms","remote":"127.0.0.1:49026","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-07-17T15:58:46.304933Z","caller":"traceutil/trace.go:171","msg":"trace[1185239758] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"370.770606ms","start":"2026-07-17T15:58:45.934107Z","end":"2026-07-17T15:58:46.304877Z","steps":["trace[1185239758] 'process raft request' (duration: 370.52783ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:46.30545Z","caller":"traceutil/trace.go:171","msg":"trace[1460344407] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"370.942191ms","start":"2026-07-17T15:58:45.934442Z","end":"2026-07-17T15:58:46.305384Z","steps":["trace[1460344407] 'process raft request' (duration: 370.395125ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:46.305099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:45.934072Z","time spent":"370.921551ms","remote":"127.0.0.1:49322","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-07-17T15:58:46.305773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:45.934422Z","time spent":"371.139137ms","remote":"127.0.0.1:49322","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-07-17T15:58:46.305799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:45.883816Z","time spent":"421.190945ms","remote":"127.0.0.1:49072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T15:58:46.306003Z","caller":"traceutil/trace.go:171","msg":"trace[1045052017] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"369.565172ms","start":"2026-07-17T15:58:45.936395Z","end":"2026-07-17T15:58:46.30596Z","steps":["trace[1045052017] 'process raft request' (duration: 368.609675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:46.306155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:45.936367Z","time spent":"369.716077ms","remote":"127.0.0.1:49072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T15:58:46.306465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.570223ms","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-07-17T15:58:46.306547Z","caller":"traceutil/trace.go:171","msg":"trace[1783740821] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:574; }","duration":"146.679656ms","start":"2026-07-17T15:58:46.159846Z","end":"2026-07-17T15:58:46.306526Z","steps":["trace[1783740821] 'agreement among raft nodes before linearized reading' (duration: 146.532442ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:46.306477Z","caller":"traceutil/trace.go:171","msg":"trace[79468495] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"367.230896ms","start":"2026-07-17T15:58:45.939228Z","end":"2026-07-17T15:58:46.306459Z","steps":["trace[79468495] 'process raft request' (duration: 366.096174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:46.30676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:45.939209Z","time spent":"367.487393ms","remote":"127.0.0.1:49072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T15:58:46.663613Z","caller":"traceutil/trace.go:171","msg":"trace[2006055242] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"186.085474ms","start":"2026-07-17T15:58:46.477503Z","end":"2026-07-17T15:58:46.663588Z","steps":["trace[2006055242] 'process raft request' (duration: 177.219172ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:46.664439Z","caller":"traceutil/trace.go:171","msg":"trace[1300295505] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"124.360254ms","start":"2026-07-17T15:58:46.540056Z","end":"2026-07-17T15:58:46.664416Z","steps":["trace[1300295505] 'process raft request' (duration: 123.911171ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:46.816581Z","caller":"traceutil/trace.go:171","msg":"trace[1435468] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"127.222936ms","start":"2026-07-17T15:58:46.689335Z","end":"2026-07-17T15:58:46.816558Z","steps":["trace[1435468] 'process raft request' (duration: 123.1617ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:46.820503Z","caller":"traceutil/trace.go:171","msg":"trace[643254663] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"129.962303ms","start":"2026-07-17T15:58:46.690503Z","end":"2026-07-17T15:58:46.820466Z","steps":["trace[643254663] 'process raft request' (duration: 129.618623ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:46.821826Z","caller":"traceutil/trace.go:171","msg":"trace[1923948138] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"130.930751ms","start":"2026-07-17T15:58:46.69086Z","end":"2026-07-17T15:58:46.821791Z","steps":["trace[1923948138] 'process raft request' (duration: 129.953053ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:47.624198Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3721274498932759002,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T15:58:47.824328Z","caller":"traceutil/trace.go:171","msg":"trace[1307511748] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"895.757263ms","start":"2026-07-17T15:58:46.928463Z","end":"2026-07-17T15:58:47.82422Z","steps":["trace[1307511748] 'process raft request' (duration: 895.408773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:47.824575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:46.928446Z","time spent":"895.98991ms","remote":"127.0.0.1:49072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T15:58:47.830548Z","caller":"traceutil/trace.go:171","msg":"trace[1281572814] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"892.319406ms","start":"2026-07-17T15:58:46.938212Z","end":"2026-07-17T15:58:47.830532Z","steps":["trace[1281572814] 'process raft request' (duration: 892.095129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:47.830622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:46.938193Z","time spent":"892.393938ms","remote":"127.0.0.1:49072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T15:58:47.830645Z","caller":"traceutil/trace.go:171","msg":"trace[1205649586] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"891.115361ms","start":"2026-07-17T15:58:46.939509Z","end":"2026-07-17T15:58:47.830624Z","steps":["trace[1205649586] 'process raft request' (duration: 890.975237ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:47.830644Z","caller":"traceutil/trace.go:171","msg":"trace[134534466] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:612; }","duration":"706.571222ms","start":"2026-07-17T15:58:47.124035Z","end":"2026-07-17T15:58:47.830606Z","steps":["trace[134534466] 'read index received' (duration: 699.853612ms)","trace[134534466] 'applied index is now lower than readState.Index' (duration: 6.71661ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T15:58:47.830719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:46.939496Z","time spent":"891.184344ms","remote":"127.0.0.1:49072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65412,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T15:58:47.830813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.492778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T15:58:47.830858Z","caller":"traceutil/trace.go:171","msg":"trace[385529924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"166.576161ms","start":"2026-07-17T15:58:47.664268Z","end":"2026-07-17T15:58:47.830844Z","steps":["trace[385529924] 'agreement among raft nodes before linearized reading' (duration: 166.499228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:47.830913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.741227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-17T15:58:47.831039Z","caller":"traceutil/trace.go:171","msg":"trace[1985960528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:593; }","duration":"707.023884ms","start":"2026-07-17T15:58:47.123993Z","end":"2026-07-17T15:58:47.831017Z","steps":["trace[1985960528] 'agreement among raft nodes before linearized reading' (duration: 706.726326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:47.831084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:47.123974Z","time spent":"707.097667ms","remote":"127.0.0.1:49322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-17T15:58:51.306524Z","caller":"traceutil/trace.go:171","msg":"trace[618475251] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"170.478591ms","start":"2026-07-17T15:58:51.136008Z","end":"2026-07-17T15:58:51.306487Z","steps":["trace[618475251] 'process raft request' (duration: 168.221217ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:54.49706Z","caller":"traceutil/trace.go:171","msg":"trace[668607536] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"131.457745ms","start":"2026-07-17T15:58:54.36558Z","end":"2026-07-17T15:58:54.497038Z","steps":["trace[668607536] 'read index received' (duration: 131.245619ms)","trace[668607536] 'applied index is now lower than readState.Index' (duration: 211.176µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T15:58:54.497167Z","caller":"traceutil/trace.go:171","msg":"trace[1691268896] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"131.789795ms","start":"2026-07-17T15:58:54.365346Z","end":"2026-07-17T15:58:54.497136Z","steps":["trace[1691268896] 'process raft request' (duration: 131.448975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:54.497277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.675131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T15:58:54.497346Z","caller":"traceutil/trace.go:171","msg":"trace[201042594] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:650; }","duration":"131.773364ms","start":"2026-07-17T15:58:54.365555Z","end":"2026-07-17T15:58:54.497328Z","steps":["trace[201042594] 'agreement among raft nodes before linearized reading' (duration: 131.593979ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:54.572212Z","caller":"traceutil/trace.go:171","msg":"trace[252424092] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"206.535082ms","start":"2026-07-17T15:58:54.365651Z","end":"2026-07-17T15:58:54.572186Z","steps":["trace[252424092] 'process raft request' (duration: 171.938212ms)","trace[252424092] 'compare' (duration: 34.347883ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T15:58:54.932692Z","caller":"traceutil/trace.go:171","msg":"trace[18448290] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:676; }","duration":"268.377704ms","start":"2026-07-17T15:58:54.664295Z","end":"2026-07-17T15:58:54.932673Z","steps":["trace[18448290] 'read index received' (duration: 268.135187ms)","trace[18448290] 'applied index is now lower than readState.Index' (duration: 241.757µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T15:58:54.932746Z","caller":"traceutil/trace.go:171","msg":"trace[410574466] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"346.113148ms","start":"2026-07-17T15:58:54.586617Z","end":"2026-07-17T15:58:54.93273Z","steps":["trace[410574466] 'process raft request' (duration: 345.878831ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:54.932794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.508338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T15:58:54.932817Z","caller":"traceutil/trace.go:171","msg":"trace[933328329] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"268.58576ms","start":"2026-07-17T15:58:54.664222Z","end":"2026-07-17T15:58:54.932808Z","steps":["trace[933328329] 'agreement among raft nodes before linearized reading' (duration: 268.519228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:54.932833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:54.586587Z","time spent":"346.194579ms","remote":"127.0.0.1:49232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T15:58:55.790621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.996444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T15:58:55.790738Z","caller":"traceutil/trace.go:171","msg":"trace[1395551999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"126.170759ms","start":"2026-07-17T15:58:55.664537Z","end":"2026-07-17T15:58:55.790708Z","steps":["trace[1395551999] 'range keys from in-memory index tree' (duration: 125.897021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:57.078581Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3721274498932759162,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T15:58:57.579094Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3721274498932759162,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T15:58:58.080259Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3721274498932759162,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T15:58:58.141733Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.636144201s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T15:58:58.142203Z","caller":"traceutil/trace.go:171","msg":"trace[160559098] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:678; }","duration":"1.56448619s","start":"2026-07-17T15:58:56.577681Z","end":"2026-07-17T15:58:58.142167Z","steps":["trace[160559098] 'read index received' (duration: 1.564235003s)","trace[160559098] 'applied index is now lower than readState.Index' (duration: 250.227µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T15:58:58.142241Z","caller":"traceutil/trace.go:171","msg":"trace[1622632713] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"1.636782369s","start":"2026-07-17T15:58:56.505403Z","end":"2026-07-17T15:58:58.142186Z","steps":["trace[1622632713] 'process raft request' (duration: 1.636606834s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:58.142501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:56.505343Z","time spent":"1.637032726s","remote":"127.0.0.1:49322","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-07-17T15:58:58.142578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.563972507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-17T15:58:58.142629Z","caller":"traceutil/trace.go:171","msg":"trace[1465812224] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:655; }","duration":"1.564073119s","start":"2026-07-17T15:58:56.578543Z","end":"2026-07-17T15:58:58.142616Z","steps":["trace[1465812224] 'agreement among raft nodes before linearized reading' (duration: 1.563824202s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:58.142673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:56.578523Z","time spent":"1.564137661s","remote":"127.0.0.1:49322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T15:58:58.142845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.477643434s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T15:58:58.142832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.174984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-17T15:58:58.142892Z","caller":"traceutil/trace.go:171","msg":"trace[1967967072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:655; }","duration":"1.477749437s","start":"2026-07-17T15:58:56.665129Z","end":"2026-07-17T15:58:58.142879Z","steps":["trace[1967967072] 'agreement among raft nodes before linearized reading' (duration: 1.477673045s)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:58:58.142923Z","caller":"traceutil/trace.go:171","msg":"trace[969753085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:655; }","duration":"221.352139ms","start":"2026-07-17T15:58:57.921551Z","end":"2026-07-17T15:58:58.142903Z","steps":["trace[969753085] 'agreement among raft nodes before linearized reading' (duration: 221.05982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:58.142934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:56.665108Z","time spent":"1.477816008s","remote":"127.0.0.1:49034","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-07-17T15:58:58.143282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.565614502s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T15:58:58.14333Z","caller":"traceutil/trace.go:171","msg":"trace[506804574] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:655; }","duration":"1.565744025s","start":"2026-07-17T15:58:56.577575Z","end":"2026-07-17T15:58:58.143319Z","steps":["trace[506804574] 'agreement among raft nodes before linearized reading' (duration: 1.564718377s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:58:58.143365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:56.577551Z","time spent":"1.565805537s","remote":"127.0.0.1:49322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T15:59:00.010487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.791072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T15:59:00.010562Z","caller":"traceutil/trace.go:171","msg":"trace[318516126] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:688; }","duration":"345.915286ms","start":"2026-07-17T15:58:59.66463Z","end":"2026-07-17T15:59:00.010545Z","steps":["trace[318516126] 'range keys from in-memory index tree' (duration: 345.71543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:59:00.010599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:58:59.664612Z","time spent":"345.978717ms","remote":"127.0.0.1:49026","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-07-17T15:59:00.010652Z","caller":"traceutil/trace.go:171","msg":"trace[630124737] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"205.26422ms","start":"2026-07-17T15:58:59.805362Z","end":"2026-07-17T15:59:00.010626Z","steps":["trace[630124737] 'process raft request' (duration: 197.842542ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:59:10.503174Z","caller":"traceutil/trace.go:171","msg":"trace[47053679] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:823; }","duration":"300.759967ms","start":"2026-07-17T15:59:10.202386Z","end":"2026-07-17T15:59:10.503146Z","steps":["trace[47053679] 'read index received' (duration: 300.533981ms)","trace[47053679] 'applied index is now lower than readState.Index' (duration: 224.956µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T15:59:10.503312Z","caller":"traceutil/trace.go:171","msg":"trace[1646711271] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"304.824661ms","start":"2026-07-17T15:59:10.198477Z","end":"2026-07-17T15:59:10.503302Z","steps":["trace[1646711271] 'process raft request' (duration: 304.507602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:59:10.503533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.5163ms","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-07-17T15:59:10.503569Z","caller":"traceutil/trace.go:171","msg":"trace[1478096020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:795; }","duration":"300.577043ms","start":"2026-07-17T15:59:10.202982Z","end":"2026-07-17T15:59:10.503559Z","steps":["trace[1478096020] 'agreement among raft nodes before linearized reading' (duration: 300.458819ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:59:10.503592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:59:10.202975Z","time spent":"300.610833ms","remote":"127.0.0.1:49322","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-07-17T15:59:10.503775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.382485ms","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-07-17T15:59:10.503815Z","caller":"traceutil/trace.go:171","msg":"trace[1077293633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:795; }","duration":"301.420546ms","start":"2026-07-17T15:59:10.202383Z","end":"2026-07-17T15:59:10.503803Z","steps":["trace[1077293633] 'agreement among raft nodes before linearized reading' (duration: 301.333674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:59:10.503853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:59:10.202362Z","time spent":"301.477737ms","remote":"127.0.0.1:49322","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-07-17T15:59:10.503874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.239173ms","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-07-17T15:59:10.503936Z","caller":"traceutil/trace.go:171","msg":"trace[1759819354] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:795; }","duration":"295.317065ms","start":"2026-07-17T15:59:10.208604Z","end":"2026-07-17T15:59:10.503921Z","steps":["trace[1759819354] 'agreement among raft nodes before linearized reading' (duration: 295.175021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:59:10.503595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:59:10.198466Z","time spent":"304.874382ms","remote":"127.0.0.1:49322","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-07-17T15:59:13.531207Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3721274498932759474,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T15:59:13.966977Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.340515006s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T15:59:13.967583Z","caller":"traceutil/trace.go:171","msg":"trace[1692113362] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:842; }","duration":"937.328665ms","start":"2026-07-17T15:59:13.03022Z","end":"2026-07-17T15:59:13.967549Z","steps":["trace[1692113362] 'read index received' (duration: 937.017427ms)","trace[1692113362] 'applied index is now lower than readState.Index' (duration: 309.818µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T15:59:13.967748Z","caller":"traceutil/trace.go:171","msg":"trace[113136826] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"1.341391081s","start":"2026-07-17T15:59:12.626346Z","end":"2026-07-17T15:59:13.967737Z","steps":["trace[113136826] 'process raft request' (duration: 1.340880456s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:59:13.967938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.685715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-17T15:59:13.968022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:59:12.626322Z","time spent":"1.341468983s","remote":"127.0.0.1:49232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T15:59:13.968049Z","caller":"traceutil/trace.go:171","msg":"trace[321538203] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:814; }","duration":"937.824929ms","start":"2026-07-17T15:59:13.030178Z","end":"2026-07-17T15:59:13.968003Z","steps":["trace[321538203] 'agreement among raft nodes before linearized reading' (duration: 937.668415ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:59:13.968141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:59:13.030158Z","time spent":"937.942453ms","remote":"127.0.0.1:49424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-17T15:59:14.025336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.308868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T15:59:14.025413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.041202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T15:59:14.025504Z","caller":"traceutil/trace.go:171","msg":"trace[562674641] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:814; }","duration":"159.192776ms","start":"2026-07-17T15:59:13.866286Z","end":"2026-07-17T15:59:14.025479Z","steps":["trace[562674641] 'agreement among raft nodes before linearized reading' (duration: 159.029992ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:59:14.025541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.028608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T15:59:14.025618Z","caller":"traceutil/trace.go:171","msg":"trace[669092122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:814; }","duration":"361.157242ms","start":"2026-07-17T15:59:13.664443Z","end":"2026-07-17T15:59:14.025601Z","steps":["trace[669092122] 'agreement among raft nodes before linearized reading' (duration: 361.030428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:59:14.025618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.118474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-17T15:59:14.025667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:59:13.664424Z","time spent":"361.235373ms","remote":"127.0.0.1:49034","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-07-17T15:59:14.02573Z","caller":"traceutil/trace.go:171","msg":"trace[1980437379] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:814; }","duration":"539.290329ms","start":"2026-07-17T15:59:13.486428Z","end":"2026-07-17T15:59:14.025718Z","steps":["trace[1980437379] 'agreement among raft nodes before linearized reading' (duration: 538.906648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:59:14.025787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:59:13.486414Z","time spent":"539.362481ms","remote":"127.0.0.1:49322","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-07-17T15:59:14.025834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.778122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T15:59:14.025877Z","caller":"traceutil/trace.go:171","msg":"trace[1577157626] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:814; }","duration":"144.842653ms","start":"2026-07-17T15:59:13.881023Z","end":"2026-07-17T15:59:14.025866Z","steps":["trace[1577157626] 'agreement among raft nodes before linearized reading' (duration: 144.783181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:59:14.02578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.646818ms","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":"warn","ts":"2026-07-17T15:59:14.026063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.286753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T15:59:14.026079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.044352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T15:59:14.0261Z","caller":"traceutil/trace.go:171","msg":"trace[1647729830] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:814; }","duration":"100.393986ms","start":"2026-07-17T15:59:13.925696Z","end":"2026-07-17T15:59:14.02609Z","steps":["trace[1647729830] 'agreement among raft nodes before linearized reading' (duration: 100.341045ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:59:14.026118Z","caller":"traceutil/trace.go:171","msg":"trace[271732704] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:814; }","duration":"159.140424ms","start":"2026-07-17T15:59:13.866968Z","end":"2026-07-17T15:59:14.026109Z","steps":["trace[271732704] 'agreement among raft nodes before linearized reading' (duration: 159.076882ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:59:14.026134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.628408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T15:59:14.026178Z","caller":"traceutil/trace.go:171","msg":"trace[1525675942] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:814; }","duration":"159.731291ms","start":"2026-07-17T15:59:13.866432Z","end":"2026-07-17T15:59:14.026163Z","steps":["trace[1525675942] 'agreement among raft nodes before linearized reading' (duration: 159.652548ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:59:14.026192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.554251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T15:59:14.026246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.203184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T15:59:14.026251Z","caller":"traceutil/trace.go:171","msg":"trace[741842739] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:814; }","duration":"100.630603ms","start":"2026-07-17T15:59:13.92561Z","end":"2026-07-17T15:59:14.026241Z","steps":["trace[741842739] 'agreement among raft nodes before linearized reading' (duration: 100.564651ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:59:14.026273Z","caller":"traceutil/trace.go:171","msg":"trace[1016844876] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:814; }","duration":"119.252665ms","start":"2026-07-17T15:59:13.907012Z","end":"2026-07-17T15:59:14.026265Z","steps":["trace[1016844876] 'agreement among raft nodes before linearized reading' (duration: 119.222975ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:59:14.02607Z","caller":"traceutil/trace.go:171","msg":"trace[1052903834] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:814; }","duration":"144.971437ms","start":"2026-07-17T15:59:13.881086Z","end":"2026-07-17T15:59:14.026058Z","steps":["trace[1052903834] 'agreement among raft nodes before linearized reading' (duration: 144.655798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:59:14.026224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.547285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T15:59:14.025424Z","caller":"traceutil/trace.go:171","msg":"trace[1297846136] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:814; }","duration":"118.433842ms","start":"2026-07-17T15:59:13.906972Z","end":"2026-07-17T15:59:14.025406Z","steps":["trace[1297846136] 'agreement among raft nodes before linearized reading' (duration: 118.284828ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T15:59:14.026438Z","caller":"traceutil/trace.go:171","msg":"trace[134591077] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:814; }","duration":"118.781222ms","start":"2026-07-17T15:59:13.907647Z","end":"2026-07-17T15:59:14.026428Z","steps":["trace[134591077] 'agreement among raft nodes before linearized reading' (duration: 118.558436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:59:14.026849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.552773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T15:59:14.026929Z","caller":"traceutil/trace.go:171","msg":"trace[835239701] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:814; }","duration":"145.666637ms","start":"2026-07-17T15:59:13.88125Z","end":"2026-07-17T15:59:14.026917Z","steps":["trace[835239701] 'agreement among raft nodes before linearized reading' (duration: 144.031561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:59:50.163976Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3721274498932759981,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T15:59:50.291879Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.21088763s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T15:59:50.292272Z","caller":"traceutil/trace.go:171","msg":"trace[1237471790] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"1.211409524s","start":"2026-07-17T15:59:49.080834Z","end":"2026-07-17T15:59:50.292243Z","steps":["trace[1237471790] 'process raft request' (duration: 1.211220999s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:59:50.292456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:59:49.080807Z","time spent":"1.211549638s","remote":"127.0.0.1:49322","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-07-17T15:59:50.953085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.652611ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T15:59:50.953201Z","caller":"traceutil/trace.go:171","msg":"trace[1888000913] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1067; }","duration":"1.289704151s","start":"2026-07-17T15:59:49.66348Z","end":"2026-07-17T15:59:50.953184Z","steps":["trace[1888000913] 'read index received' (duration: 628.647116ms)","trace[1888000913] 'applied index is now lower than readState.Index' (duration: 661.055654ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T15:59:50.953456Z","caller":"traceutil/trace.go:171","msg":"trace[1580845114] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"990.554749ms","start":"2026-07-17T15:59:49.962888Z","end":"2026-07-17T15:59:50.953443Z","steps":["trace[1580845114] 'process raft request' (duration: 492.446425ms)","trace[1580845114] 'compare' (duration: 497.416834ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T15:59:50.953534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:59:49.962852Z","time spent":"990.638311ms","remote":"127.0.0.1:49322","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-07-17T15:59:50.953646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.350258ms","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-07-17T15:59:50.953714Z","caller":"traceutil/trace.go:171","msg":"trace[884919573] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1031; }","duration":"746.455671ms","start":"2026-07-17T15:59:50.207237Z","end":"2026-07-17T15:59:50.953692Z","steps":["trace[884919573] 'agreement among raft nodes before linearized reading' (duration: 746.255435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:59:50.953758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:59:50.207219Z","time spent":"746.529572ms","remote":"127.0.0.1:49322","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-07-17T15:59:50.953747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.779672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-17T15:59:50.953821Z","caller":"traceutil/trace.go:171","msg":"trace[2024715008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1031; }","duration":"195.877295ms","start":"2026-07-17T15:59:50.757927Z","end":"2026-07-17T15:59:50.953804Z","steps":["trace[2024715008] 'agreement among raft nodes before linearized reading' (duration: 195.745311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:59:50.953942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.290460032s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T15:59:50.953977Z","caller":"traceutil/trace.go:171","msg":"trace[334860587] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1031; }","duration":"1.290539233s","start":"2026-07-17T15:59:49.663428Z","end":"2026-07-17T15:59:50.953968Z","steps":["trace[334860587] 'agreement among raft nodes before linearized reading' (duration: 1.290486022s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:59:50.953997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T15:59:49.663406Z","time spent":"1.290587375s","remote":"127.0.0.1:49034","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-07-17T15:59:50.95406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.3214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-17T15:59:50.954104Z","caller":"traceutil/trace.go:171","msg":"trace[1625635841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1031; }","duration":"210.390852ms","start":"2026-07-17T15:59:50.743698Z","end":"2026-07-17T15:59:50.954089Z","steps":["trace[1625635841] 'agreement among raft nodes before linearized reading' (duration: 209.986361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:59:50.954119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.35441ms","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-07-17T15:59:50.954166Z","caller":"traceutil/trace.go:171","msg":"trace[1918988552] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1031; }","duration":"195.448883ms","start":"2026-07-17T15:59:50.758706Z","end":"2026-07-17T15:59:50.954155Z","steps":["trace[1918988552] 'agreement among raft nodes before linearized reading' (duration: 195.277108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T15:59:50.954652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.934108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T15:59:50.954695Z","caller":"traceutil/trace.go:171","msg":"trace[1698604104] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1031; }","duration":"196.020869ms","start":"2026-07-17T15:59:50.758662Z","end":"2026-07-17T15:59:50.954683Z","steps":["trace[1698604104] 'agreement among raft nodes before linearized reading' (duration: 194.870266ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:12.931052Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3721274498932760171,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T16:00:13.285187Z","caller":"traceutil/trace.go:171","msg":"trace[676882459] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1144; }","duration":"854.222385ms","start":"2026-07-17T16:00:12.430935Z","end":"2026-07-17T16:00:13.285157Z","steps":["trace[676882459] 'read index received' (duration: 854.05437ms)","trace[676882459] 'applied index is now lower than readState.Index' (duration: 166.565µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:00:13.285313Z","caller":"traceutil/trace.go:171","msg":"trace[124477467] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"899.205037ms","start":"2026-07-17T16:00:12.386093Z","end":"2026-07-17T16:00:13.285298Z","steps":["trace[124477467] 'process raft request' (duration: 898.888388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:13.285437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:12.386059Z","time spent":"899.287369ms","remote":"127.0.0.1:49322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:00:13.285592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.597376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T16:00:13.28573Z","caller":"traceutil/trace.go:171","msg":"trace[1031554174] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1103; }","duration":"854.827192ms","start":"2026-07-17T16:00:12.430878Z","end":"2026-07-17T16:00:13.285706Z","steps":["trace[1031554174] 'agreement among raft nodes before linearized reading' (duration: 854.508613ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:13.285853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:12.430851Z","time spent":"854.950155ms","remote":"127.0.0.1:49514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-17T16:00:13.350184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.957829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-17T16:00:13.350432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.818264ms","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":"warn","ts":"2026-07-17T16:00:13.350211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.306238ms","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-07-17T16:00:13.350544Z","caller":"traceutil/trace.go:171","msg":"trace[472005843] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1104; }","duration":"256.998909ms","start":"2026-07-17T16:00:13.093521Z","end":"2026-07-17T16:00:13.35052Z","steps":["trace[472005843] 'agreement among raft nodes before linearized reading' (duration: 256.716031ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:00:13.350437Z","caller":"traceutil/trace.go:171","msg":"trace[1535233601] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1104; }","duration":"740.289688ms","start":"2026-07-17T16:00:12.610119Z","end":"2026-07-17T16:00:13.350408Z","steps":["trace[1535233601] 'agreement among raft nodes before linearized reading' (duration: 739.878527ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:00:13.350556Z","caller":"traceutil/trace.go:171","msg":"trace[939704715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1104; }","duration":"262.660168ms","start":"2026-07-17T16:00:13.087879Z","end":"2026-07-17T16:00:13.350539Z","steps":["trace[939704715] 'agreement among raft nodes before linearized reading' (duration: 262.164454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:13.35073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:12.610098Z","time spent":"740.610558ms","remote":"127.0.0.1:49506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-07-17T16:00:13.350297Z","caller":"traceutil/trace.go:171","msg":"trace[1973255807] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"409.854318ms","start":"2026-07-17T16:00:12.940412Z","end":"2026-07-17T16:00:13.350266Z","steps":["trace[1973255807] 'process raft request' (duration: 409.375605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:13.351024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:12.940378Z","time spent":"410.587398ms","remote":"127.0.0.1:49322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:00:13.350293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.884084ms","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-07-17T16:00:13.351114Z","caller":"traceutil/trace.go:171","msg":"trace[1702527620] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1104; }","duration":"263.79298ms","start":"2026-07-17T16:00:13.087303Z","end":"2026-07-17T16:00:13.351096Z","steps":["trace[1702527620] 'agreement among raft nodes before linearized reading' (duration: 262.873734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:13.350211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.654473ms","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-07-17T16:00:13.351248Z","caller":"traceutil/trace.go:171","msg":"trace[1154840440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1104; }","duration":"281.740793ms","start":"2026-07-17T16:00:13.06949Z","end":"2026-07-17T16:00:13.351231Z","steps":["trace[1154840440] 'agreement among raft nodes before linearized reading' (duration: 280.574931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:13.350336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.873866ms","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-07-17T16:00:13.351441Z","caller":"traceutil/trace.go:171","msg":"trace[1209380943] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1104; }","duration":"263.015568ms","start":"2026-07-17T16:00:13.088411Z","end":"2026-07-17T16:00:13.351427Z","steps":["trace[1209380943] 'agreement among raft nodes before linearized reading' (duration: 261.851435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:13.35021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.171039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T16:00:13.35154Z","caller":"traceutil/trace.go:171","msg":"trace[1011302859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1104; }","duration":"687.550128ms","start":"2026-07-17T16:00:12.66397Z","end":"2026-07-17T16:00:13.35152Z","steps":["trace[1011302859] 'agreement among raft nodes before linearized reading' (duration: 686.153169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:13.351601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:12.663953Z","time spent":"687.63237ms","remote":"127.0.0.1:49034","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-07-17T16:00:13.702951Z","caller":"traceutil/trace.go:171","msg":"trace[1205544231] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"347.810368ms","start":"2026-07-17T16:00:13.355113Z","end":"2026-07-17T16:00:13.702923Z","steps":["trace[1205544231] 'process raft request' (duration: 305.508502ms)","trace[1205544231] 'compare' (duration: 42.184463ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:00:13.703129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:13.355079Z","time spent":"347.945112ms","remote":"127.0.0.1:49322","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-07-17T16:00:13.728413Z","caller":"traceutil/trace.go:171","msg":"trace[689757743] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"372.780729ms","start":"2026-07-17T16:00:13.355606Z","end":"2026-07-17T16:00:13.728387Z","steps":["trace[689757743] 'process raft request' (duration: 372.673376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:13.728764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:13.355572Z","time spent":"373.120128ms","remote":"127.0.0.1:49322","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-07-17T16:00:13.728922Z","caller":"traceutil/trace.go:171","msg":"trace[1083355751] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"373.061586ms","start":"2026-07-17T16:00:13.35585Z","end":"2026-07-17T16:00:13.728911Z","steps":["trace[1083355751] 'process raft request' (duration: 372.47324ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:13.728984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:13.355839Z","time spent":"373.110928ms","remote":"127.0.0.1:49322","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-07-17T16:00:13.729003Z","caller":"traceutil/trace.go:171","msg":"trace[862517205] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"373.51927ms","start":"2026-07-17T16:00:13.355465Z","end":"2026-07-17T16:00:13.728985Z","steps":["trace[862517205] 'process raft request' (duration: 372.550922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:13.729091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:13.355449Z","time spent":"373.608092ms","remote":"127.0.0.1:49322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T16:00:13.729407Z","caller":"traceutil/trace.go:171","msg":"trace[1593406275] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"372.763818ms","start":"2026-07-17T16:00:13.35662Z","end":"2026-07-17T16:00:13.729384Z","steps":["trace[1593406275] 'process raft request' (duration: 371.974356ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:13.729543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:13.356609Z","time spent":"372.857141ms","remote":"127.0.0.1:49322","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-07-17T16:00:16.070756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.964634ms","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-07-17T16:00:16.070793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.926698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T16:00:16.070883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.951175ms","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-07-17T16:00:16.070879Z","caller":"traceutil/trace.go:171","msg":"trace[665075362] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1110; }","duration":"406.072533ms","start":"2026-07-17T16:00:15.664784Z","end":"2026-07-17T16:00:16.070856Z","steps":["trace[665075362] 'range keys from in-memory index tree' (duration: 405.771824ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:00:16.070911Z","caller":"traceutil/trace.go:171","msg":"trace[744419818] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1110; }","duration":"335.993156ms","start":"2026-07-17T16:00:15.73491Z","end":"2026-07-17T16:00:16.070903Z","steps":["trace[744419818] 'range keys from in-memory index tree' (duration: 335.821341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:16.070938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:15.734902Z","time spent":"336.030397ms","remote":"127.0.0.1:49322","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-07-17T16:00:16.070931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:15.664755Z","time spent":"406.162466ms","remote":"127.0.0.1:49034","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-07-17T16:00:16.071187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.594181ms","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":"warn","ts":"2026-07-17T16:00:16.071243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.210562ms","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-07-17T16:00:16.071302Z","caller":"traceutil/trace.go:171","msg":"trace[1165816563] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1110; }","duration":"363.766356ms","start":"2026-07-17T16:00:15.707521Z","end":"2026-07-17T16:00:16.071287Z","steps":["trace[1165816563] 'range keys from in-memory index tree' (duration: 363.328594ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:00:16.071327Z","caller":"traceutil/trace.go:171","msg":"trace[654779528] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1110; }","duration":"336.354427ms","start":"2026-07-17T16:00:15.734955Z","end":"2026-07-17T16:00:16.07131Z","steps":["trace[654779528] 'range keys from in-memory index tree' (duration: 336.036678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:16.071347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:15.707497Z","time spent":"363.837317ms","remote":"127.0.0.1:49322","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-07-17T16:00:16.071375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:15.734936Z","time spent":"336.426289ms","remote":"127.0.0.1:49322","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-07-17T16:00:16.070827Z","caller":"traceutil/trace.go:171","msg":"trace[1670214408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1110; }","duration":"337.131339ms","start":"2026-07-17T16:00:15.73368Z","end":"2026-07-17T16:00:16.070811Z","steps":["trace[1670214408] 'range keys from in-memory index tree' (duration: 336.700836ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:16.07143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:15.733655Z","time spent":"337.764907ms","remote":"127.0.0.1:49322","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-07-17T16:00:16.071544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.761066ms","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-07-17T16:00:16.071624Z","caller":"traceutil/trace.go:171","msg":"trace[1432576354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1110; }","duration":"337.90555ms","start":"2026-07-17T16:00:15.733698Z","end":"2026-07-17T16:00:16.071604Z","steps":["trace[1432576354] 'range keys from in-memory index tree' (duration: 336.965873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:16.071751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:15.733675Z","time spent":"338.055624ms","remote":"127.0.0.1:49322","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-07-17T16:00:17.502239Z","caller":"traceutil/trace.go:171","msg":"trace[1483097624] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"144.949537ms","start":"2026-07-17T16:00:17.357258Z","end":"2026-07-17T16:00:17.502207Z","steps":["trace[1483097624] 'process raft request' (duration: 144.733021ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:00:18.297349Z","caller":"traceutil/trace.go:171","msg":"trace[1460368317] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"132.084896ms","start":"2026-07-17T16:00:18.165229Z","end":"2026-07-17T16:00:18.297314Z","steps":["trace[1460368317] 'process raft request' (duration: 122.045594ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:00:18.303646Z","caller":"traceutil/trace.go:171","msg":"trace[724270487] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"135.501971ms","start":"2026-07-17T16:00:18.168128Z","end":"2026-07-17T16:00:18.30363Z","steps":["trace[724270487] 'process raft request' (duration: 135.46601ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:00:18.30397Z","caller":"traceutil/trace.go:171","msg":"trace[1389775824] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"137.975531ms","start":"2026-07-17T16:00:18.165827Z","end":"2026-07-17T16:00:18.303802Z","steps":["trace[1389775824] 'process raft request' (duration: 137.729804ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:00:18.303964Z","caller":"traceutil/trace.go:171","msg":"trace[70925176] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"137.997212ms","start":"2026-07-17T16:00:18.165723Z","end":"2026-07-17T16:00:18.30372Z","steps":["trace[70925176] 'process raft request' (duration: 137.730564ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:00:33.659033Z","caller":"traceutil/trace.go:171","msg":"trace[1126544799] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"173.612931ms","start":"2026-07-17T16:00:33.485399Z","end":"2026-07-17T16:00:33.659012Z","steps":["trace[1126544799] 'process raft request' (duration: 173.461356ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:00:36.065102Z","caller":"traceutil/trace.go:171","msg":"trace[1596069006] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"398.349476ms","start":"2026-07-17T16:00:35.666716Z","end":"2026-07-17T16:00:36.065066Z","steps":["trace[1596069006] 'process raft request' (duration: 398.073088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:36.065374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:35.666696Z","time spent":"398.532721ms","remote":"127.0.0.1:49322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:00:36.641143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.651584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T16:00:36.641242Z","caller":"traceutil/trace.go:171","msg":"trace[252944482] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1227; }","duration":"249.786628ms","start":"2026-07-17T16:00:36.391433Z","end":"2026-07-17T16:00:36.64122Z","steps":["trace[252944482] 'range keys from in-memory index tree' (duration: 249.48699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:36.641346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.165986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-17T16:00:36.64138Z","caller":"traceutil/trace.go:171","msg":"trace[1323395047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1227; }","duration":"200.218587ms","start":"2026-07-17T16:00:36.441149Z","end":"2026-07-17T16:00:36.641368Z","steps":["trace[1323395047] 'range keys from in-memory index tree' (duration: 199.998481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:36.641395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.577622ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T16:00:36.641558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.090818ms","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-07-17T16:00:36.641578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.379714ms","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-07-17T16:00:36.641582Z","caller":"traceutil/trace.go:171","msg":"trace[1608648936] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1227; }","duration":"254.182701ms","start":"2026-07-17T16:00:36.387391Z","end":"2026-07-17T16:00:36.641573Z","steps":["trace[1608648936] 'range keys from in-memory index tree' (duration: 253.951965ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:00:36.6416Z","caller":"traceutil/trace.go:171","msg":"trace[1656004970] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1227; }","duration":"415.448596ms","start":"2026-07-17T16:00:36.226144Z","end":"2026-07-17T16:00:36.641592Z","steps":["trace[1656004970] 'range keys from in-memory index tree' (duration: 415.261011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:36.641621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:36.226113Z","time spent":"415.499667ms","remote":"127.0.0.1:49322","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-07-17T16:00:36.641622Z","caller":"traceutil/trace.go:171","msg":"trace[781808698] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1227; }","duration":"213.687155ms","start":"2026-07-17T16:00:36.427799Z","end":"2026-07-17T16:00:36.641486Z","steps":["trace[781808698] 'range keys from in-memory index tree' (duration: 213.567902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:36.641143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.624037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-17T16:00:36.641686Z","caller":"traceutil/trace.go:171","msg":"trace[326220611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1227; }","duration":"207.235214ms","start":"2026-07-17T16:00:36.434442Z","end":"2026-07-17T16:00:36.641678Z","steps":["trace[326220611] 'range keys from in-memory index tree' (duration: 206.421622ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:00:51.164642Z","caller":"traceutil/trace.go:171","msg":"trace[1406066405] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1325; }","duration":"420.650251ms","start":"2026-07-17T16:00:50.743961Z","end":"2026-07-17T16:00:51.164611Z","steps":["trace[1406066405] 'read index received' (duration: 420.393164ms)","trace[1406066405] 'applied index is now lower than readState.Index' (duration: 255.927µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:00:51.164725Z","caller":"traceutil/trace.go:171","msg":"trace[307048964] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"437.418852ms","start":"2026-07-17T16:00:50.727277Z","end":"2026-07-17T16:00:51.164696Z","steps":["trace[307048964] 'process raft request' (duration: 437.134034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:51.16485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:50.727245Z","time spent":"437.545175ms","remote":"127.0.0.1:49322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:00:51.164909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.899728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-17T16:00:51.164953Z","caller":"traceutil/trace.go:171","msg":"trace[1044966575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1274; }","duration":"421.022142ms","start":"2026-07-17T16:00:50.743918Z","end":"2026-07-17T16:00:51.16494Z","steps":["trace[1044966575] 'agreement among raft nodes before linearized reading' (duration: 420.849137ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:51.164985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:50.743898Z","time spent":"421.080894ms","remote":"127.0.0.1:49322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T16:00:51.165033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.85545ms","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-07-17T16:00:51.165093Z","caller":"traceutil/trace.go:171","msg":"trace[371670397] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1274; }","duration":"419.919721ms","start":"2026-07-17T16:00:50.745158Z","end":"2026-07-17T16:00:51.165078Z","steps":["trace[371670397] 'agreement among raft nodes before linearized reading' (duration: 419.780448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:51.165145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:50.74513Z","time spent":"420.000503ms","remote":"127.0.0.1:49322","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-07-17T16:00:51.165153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.934319ms","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-07-17T16:00:51.165288Z","caller":"traceutil/trace.go:171","msg":"trace[1077569666] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1274; }","duration":"416.144576ms","start":"2026-07-17T16:00:50.749121Z","end":"2026-07-17T16:00:51.165266Z","steps":["trace[1077569666] 'agreement among raft nodes before linearized reading' (duration: 415.803316ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:51.165345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:50.749096Z","time spent":"416.237718ms","remote":"127.0.0.1:49322","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-07-17T16:00:51.165722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.492585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-17T16:00:51.165798Z","caller":"traceutil/trace.go:171","msg":"trace[1551345378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1274; }","duration":"421.601898ms","start":"2026-07-17T16:00:50.744181Z","end":"2026-07-17T16:00:51.165782Z","steps":["trace[1551345378] 'agreement among raft nodes before linearized reading' (duration: 420.59771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:51.165841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:50.744163Z","time spent":"421.66733ms","remote":"127.0.0.1:49322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-17T16:00:54.10559Z","caller":"traceutil/trace.go:171","msg":"trace[2103103996] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"135.653946ms","start":"2026-07-17T16:00:53.969883Z","end":"2026-07-17T16:00:54.105537Z","steps":["trace[2103103996] 'process raft request' (duration: 134.317248ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:00:54.109343Z","caller":"traceutil/trace.go:171","msg":"trace[1434329793] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"139.135783ms","start":"2026-07-17T16:00:53.970188Z","end":"2026-07-17T16:00:54.109324Z","steps":["trace[1434329793] 'process raft request' (duration: 138.565307ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:00:54.109139Z","caller":"traceutil/trace.go:171","msg":"trace[1352446705] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"135.617494ms","start":"2026-07-17T16:00:53.973499Z","end":"2026-07-17T16:00:54.109116Z","steps":["trace[1352446705] 'process raft request' (duration: 135.348367ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:56.785098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.306434ms","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-07-17T16:00:56.785247Z","caller":"traceutil/trace.go:171","msg":"trace[298809919] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1323; }","duration":"357.482879ms","start":"2026-07-17T16:00:56.427749Z","end":"2026-07-17T16:00:56.785232Z","steps":["trace[298809919] 'range keys from in-memory index tree' (duration: 357.278944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:56.785598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.313094ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T16:00:56.785743Z","caller":"traceutil/trace.go:171","msg":"trace[965773570] linearizableReadLoop","detail":"{readStateIndex:1377; appliedIndex:1376; }","duration":"495.490881ms","start":"2026-07-17T16:00:56.290231Z","end":"2026-07-17T16:00:56.785722Z","steps":["trace[965773570] 'read index received' (duration: 147.980142ms)","trace[965773570] 'applied index is now lower than readState.Index' (duration: 347.509289ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:00:56.785859Z","caller":"traceutil/trace.go:171","msg":"trace[1550770871] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"535.219526ms","start":"2026-07-17T16:00:56.250626Z","end":"2026-07-17T16:00:56.785846Z","steps":["trace[1550770871] 'process raft request' (duration: 187.555452ms)","trace[1550770871] 'compare' (duration: 347.161749ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:00:56.785924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:56.250605Z","time spent":"535.291198ms","remote":"127.0.0.1:49106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":843,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T16:00:56.786003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.765309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T16:00:56.786036Z","caller":"traceutil/trace.go:171","msg":"trace[1057261491] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1324; }","duration":"495.823091ms","start":"2026-07-17T16:00:56.290203Z","end":"2026-07-17T16:00:56.786026Z","steps":["trace[1057261491] 'agreement among raft nodes before linearized reading' (duration: 495.698577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:56.786065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:56.290178Z","time spent":"495.881143ms","remote":"127.0.0.1:49322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-17T16:00:56.786054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.298191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T16:00:56.786124Z","caller":"traceutil/trace.go:171","msg":"trace[573145290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1324; }","duration":"122.501487ms","start":"2026-07-17T16:00:56.663606Z","end":"2026-07-17T16:00:56.786108Z","steps":["trace[573145290] 'agreement among raft nodes before linearized reading' (duration: 122.277781ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:00:56.952533Z","caller":"traceutil/trace.go:171","msg":"trace[441690326] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"107.725402ms","start":"2026-07-17T16:00:56.844774Z","end":"2026-07-17T16:00:56.952499Z","steps":["trace[441690326] 'process raft request' (duration: 70.494918ms)","trace[441690326] 'compare' (duration: 37.058279ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:00:57.327641Z","caller":"traceutil/trace.go:171","msg":"trace[1185503766] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1380; }","duration":"117.286831ms","start":"2026-07-17T16:00:57.210335Z","end":"2026-07-17T16:00:57.327622Z","steps":["trace[1185503766] 'read index received' (duration: 117.153497ms)","trace[1185503766] 'applied index is now lower than readState.Index' (duration: 132.694µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:00:57.327843Z","caller":"traceutil/trace.go:171","msg":"trace[1516182488] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"121.195651ms","start":"2026-07-17T16:00:57.206533Z","end":"2026-07-17T16:00:57.327729Z","steps":["trace[1516182488] 'process raft request' (duration: 120.972344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:57.327881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.541517ms","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-07-17T16:00:57.327907Z","caller":"traceutil/trace.go:171","msg":"trace[1612342894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1328; }","duration":"117.604299ms","start":"2026-07-17T16:00:57.210296Z","end":"2026-07-17T16:00:57.3279Z","steps":["trace[1612342894] 'agreement among raft nodes before linearized reading' (duration: 117.463986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:57.343884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.753122ms","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-07-17T16:00:57.343941Z","caller":"traceutil/trace.go:171","msg":"trace[1702892180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1329; }","duration":"128.833954ms","start":"2026-07-17T16:00:57.215093Z","end":"2026-07-17T16:00:57.343927Z","steps":["trace[1702892180] 'agreement among raft nodes before linearized reading' (duration: 128.69314ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:00:57.343917Z","caller":"traceutil/trace.go:171","msg":"trace[1439410365] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"133.382433ms","start":"2026-07-17T16:00:57.210494Z","end":"2026-07-17T16:00:57.343877Z","steps":["trace[1439410365] 'process raft request' (duration: 133.077494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:57.343887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.033186ms","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-07-17T16:00:57.344062Z","caller":"traceutil/trace.go:171","msg":"trace[388637343] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1329; }","duration":"126.234971ms","start":"2026-07-17T16:00:57.217809Z","end":"2026-07-17T16:00:57.344044Z","steps":["trace[388637343] 'agreement among raft nodes before linearized reading' (duration: 126.002785ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:57.344154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.289083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T16:00:57.344181Z","caller":"traceutil/trace.go:171","msg":"trace[538733107] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1329; }","duration":"126.316724ms","start":"2026-07-17T16:00:57.217854Z","end":"2026-07-17T16:00:57.344171Z","steps":["trace[538733107] 'agreement among raft nodes before linearized reading' (duration: 126.274373ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:00:58.905165Z","caller":"traceutil/trace.go:171","msg":"trace[728658833] linearizableReadLoop","detail":"{readStateIndex:1386; appliedIndex:1385; }","duration":"245.192399ms","start":"2026-07-17T16:00:58.659949Z","end":"2026-07-17T16:00:58.905142Z","steps":["trace[728658833] 'read index received' (duration: 244.907801ms)","trace[728658833] 'applied index is now lower than readState.Index' (duration: 283.868µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:00:58.905259Z","caller":"traceutil/trace.go:171","msg":"trace[1939285522] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"697.984553ms","start":"2026-07-17T16:00:58.207219Z","end":"2026-07-17T16:00:58.905204Z","steps":["trace[1939285522] 'process raft request' (duration: 697.760896ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:58.905387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.193187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T16:00:58.905466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:58.207189Z","time spent":"698.138207ms","remote":"127.0.0.1:49322","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-07-17T16:00:58.905493Z","caller":"traceutil/trace.go:171","msg":"trace[933566258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1333; }","duration":"241.374992ms","start":"2026-07-17T16:00:58.664094Z","end":"2026-07-17T16:00:58.905469Z","steps":["trace[933566258] 'agreement among raft nodes before linearized reading' (duration: 241.207417ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:58.905508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.536629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.207\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-17T16:00:58.905656Z","caller":"traceutil/trace.go:171","msg":"trace[1231097187] range","detail":"{range_begin:/registry/masterleases/199.19.213.207; range_end:; response_count:1; response_revision:1333; }","duration":"245.740455ms","start":"2026-07-17T16:00:58.659894Z","end":"2026-07-17T16:00:58.905634Z","steps":["trace[1231097187] 'agreement among raft nodes before linearized reading' (duration: 245.356324ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:58.905731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.679924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T16:00:58.905869Z","caller":"traceutil/trace.go:171","msg":"trace[355916428] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1333; }","duration":"188.983912ms","start":"2026-07-17T16:00:58.716858Z","end":"2026-07-17T16:00:58.905842Z","steps":["trace[355916428] 'agreement among raft nodes before linearized reading' (duration: 188.645653ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:00:59.063499Z","caller":"traceutil/trace.go:171","msg":"trace[1641055574] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"137.379314ms","start":"2026-07-17T16:00:58.926098Z","end":"2026-07-17T16:00:59.063477Z","steps":["trace[1641055574] 'process raft request' (duration: 69.209811ms)","trace[1641055574] 'compare' (duration: 68.043219ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:00:59.786873Z","caller":"traceutil/trace.go:171","msg":"trace[301568701] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"419.026767ms","start":"2026-07-17T16:00:59.367823Z","end":"2026-07-17T16:00:59.78685Z","steps":["trace[301568701] 'process raft request' (duration: 418.882903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:00:59.787021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:59.367802Z","time spent":"419.138549ms","remote":"127.0.0.1:49322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:01:00.807412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.903073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T16:01:00.807478Z","caller":"traceutil/trace.go:171","msg":"trace[338032425] linearizableReadLoop","detail":"{readStateIndex:1390; appliedIndex:1388; }","duration":"1.427440428s","start":"2026-07-17T16:00:59.380026Z","end":"2026-07-17T16:01:00.807467Z","steps":["trace[338032425] 'read index received' (duration: 406.719891ms)","trace[338032425] 'applied index is now lower than readState.Index' (duration: 1.020719917s)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:01:00.807642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.147063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T16:01:00.807664Z","caller":"traceutil/trace.go:171","msg":"trace[1637749261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1336; }","duration":"145.170714ms","start":"2026-07-17T16:01:00.662488Z","end":"2026-07-17T16:01:00.807659Z","steps":["trace[1637749261] 'agreement among raft nodes before linearized reading' (duration: 145.134793ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:01:00.807774Z","caller":"traceutil/trace.go:171","msg":"trace[1965954226] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"1.439738994s","start":"2026-07-17T16:00:59.368029Z","end":"2026-07-17T16:01:00.807768Z","steps":["trace[1965954226] 'process raft request' (duration: 732.404518ms)","trace[1965954226] 'compare' (duration: 706.77648ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T16:01:00.80781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:59.368018Z","time spent":"1.439771914s","remote":"127.0.0.1:49322","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-07-17T16:01:00.80785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.39899637s","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":"warn","ts":"2026-07-17T16:01:00.807925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.427906622s","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-07-17T16:01:00.807944Z","caller":"traceutil/trace.go:171","msg":"trace[1233845869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1336; }","duration":"1.428022995s","start":"2026-07-17T16:00:59.379916Z","end":"2026-07-17T16:01:00.807939Z","steps":["trace[1233845869] 'agreement among raft nodes before linearized reading' (duration: 1.427953363s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:01:00.807957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:59.379886Z","time spent":"1.428068256s","remote":"127.0.0.1:49322","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-07-17T16:01:00.807998Z","caller":"traceutil/trace.go:171","msg":"trace[892495046] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1336; }","duration":"1.399151484s","start":"2026-07-17T16:00:59.408771Z","end":"2026-07-17T16:01:00.807922Z","steps":["trace[892495046] 'agreement among raft nodes before linearized reading' (duration: 1.398840805s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:01:00.808072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:59.408687Z","time spent":"1.399371481s","remote":"127.0.0.1:49322","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-07-17T16:01:00.808143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.089116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-17T16:01:00.808162Z","caller":"traceutil/trace.go:171","msg":"trace[741708273] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1336; }","duration":"981.138648ms","start":"2026-07-17T16:00:59.827019Z","end":"2026-07-17T16:01:00.808158Z","steps":["trace[741708273] 'agreement among raft nodes before linearized reading' (duration: 981.081756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:01:00.808176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:59.827003Z","time spent":"981.170249ms","remote":"127.0.0.1:49322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-17T16:01:00.808276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.398997181s","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-07-17T16:01:00.808343Z","caller":"traceutil/trace.go:171","msg":"trace[209170289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1336; }","duration":"1.399085882s","start":"2026-07-17T16:00:59.40924Z","end":"2026-07-17T16:01:00.808326Z","steps":["trace[209170289] 'agreement among raft nodes before linearized reading' (duration: 1.398927558s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:01:00.808394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:00:59.409231Z","time spent":"1.399150665s","remote":"127.0.0.1:49322","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-07-17T16:01:01.107098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.192947ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T16:01:01.107253Z","caller":"traceutil/trace.go:171","msg":"trace[1561402193] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"294.687728ms","start":"2026-07-17T16:01:00.812545Z","end":"2026-07-17T16:01:01.107233Z","steps":["trace[1561402193] 'process raft request' (duration: 120.246643ms)","trace[1561402193] 'compare' (duration: 174.038333ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:01:01.132098Z","caller":"traceutil/trace.go:171","msg":"trace[97652234] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"318.802624ms","start":"2026-07-17T16:01:00.813262Z","end":"2026-07-17T16:01:01.132065Z","steps":["trace[97652234] 'process raft request' (duration: 318.554987ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:01:01.132312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:01:00.813253Z","time spent":"318.948169ms","remote":"127.0.0.1:49322","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-07-17T16:01:01.132436Z","caller":"traceutil/trace.go:171","msg":"trace[1060240497] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"318.28189ms","start":"2026-07-17T16:01:00.814125Z","end":"2026-07-17T16:01:01.132407Z","steps":["trace[1060240497] 'process raft request' (duration: 317.879268ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:01:01.132599Z","caller":"traceutil/trace.go:171","msg":"trace[1232055486] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"318.366752ms","start":"2026-07-17T16:01:00.814219Z","end":"2026-07-17T16:01:01.132586Z","steps":["trace[1232055486] 'process raft request' (duration: 318.059273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:01:01.132626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:01:00.814119Z","time spent":"318.403123ms","remote":"127.0.0.1:49322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T16:01:01.132674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:01:00.8142Z","time spent":"318.438354ms","remote":"127.0.0.1:49322","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-07-17T16:01:01.132965Z","caller":"traceutil/trace.go:171","msg":"trace[340785712] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"220.439975ms","start":"2026-07-17T16:01:00.912512Z","end":"2026-07-17T16:01:01.132952Z","steps":["trace[340785712] 'process raft request' (duration: 220.022923ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:01:01.923683Z","caller":"traceutil/trace.go:171","msg":"trace[411836793] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"128.048733ms","start":"2026-07-17T16:01:01.795593Z","end":"2026-07-17T16:01:01.923642Z","steps":["trace[411836793] 'process raft request' (duration: 127.827117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:01:07.366223Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3721274498932760786,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T16:01:07.867112Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3721274498932760786,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T16:01:08.368012Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3721274498932760786,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T16:01:08.634543Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.797220892s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T16:01:08.635023Z","caller":"traceutil/trace.go:171","msg":"trace[1311639185] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"1.797789588s","start":"2026-07-17T16:01:06.837204Z","end":"2026-07-17T16:01:08.634993Z","steps":["trace[1311639185] 'process raft request' (duration: 1.797605492s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:01:08.635197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:01:06.837181Z","time spent":"1.79791496s","remote":"127.0.0.1:49322","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-07-17T16:01:08.742109Z","caller":"traceutil/trace.go:171","msg":"trace[2053961771] linearizableReadLoop","detail":"{readStateIndex:1441; appliedIndex:1440; }","duration":"1.876784602s","start":"2026-07-17T16:01:06.86529Z","end":"2026-07-17T16:01:08.742075Z","steps":["trace[2053961771] 'read index received' (duration: 1.769697899s)","trace[2053961771] 'applied index is now lower than readState.Index' (duration: 107.085273ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:01:08.742274Z","caller":"traceutil/trace.go:171","msg":"trace[1767772647] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"1.581989256s","start":"2026-07-17T16:01:07.160244Z","end":"2026-07-17T16:01:08.742234Z","steps":["trace[1767772647] 'process raft request' (duration: 1.576922904s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:01:08.742402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:01:07.160221Z","time spent":"1.58211261s","remote":"127.0.0.1:49322","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-07-17T16:01:08.742435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.877134893s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:88298"} {"level":"info","ts":"2026-07-17T16:01:08.742472Z","caller":"traceutil/trace.go:171","msg":"trace[1812440951] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1386; }","duration":"1.877234825s","start":"2026-07-17T16:01:06.865225Z","end":"2026-07-17T16:01:08.74246Z","steps":["trace[1812440951] 'agreement among raft nodes before linearized reading' (duration: 1.877008329s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:01:08.742512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:01:06.865199Z","time spent":"1.877305097s","remote":"127.0.0.1:49082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":88320,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-07-17T16:01:08.742605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.876879825s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-07-17T16:01:08.742681Z","caller":"traceutil/trace.go:171","msg":"trace[227337572] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1386; }","duration":"1.877000379s","start":"2026-07-17T16:01:06.865661Z","end":"2026-07-17T16:01:08.742661Z","steps":["trace[227337572] 'agreement among raft nodes before linearized reading' (duration: 1.876872985s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:01:08.742722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:01:06.865644Z","time spent":"1.87706721s","remote":"127.0.0.1:49092","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-07-17T16:01:08.74274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.612488021s","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-07-17T16:01:08.742752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.578121957s","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-07-17T16:01:08.742788Z","caller":"traceutil/trace.go:171","msg":"trace[1346664453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1386; }","duration":"1.612575843s","start":"2026-07-17T16:01:07.130199Z","end":"2026-07-17T16:01:08.742775Z","steps":["trace[1346664453] 'agreement among raft nodes before linearized reading' (duration: 1.612391148s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:01:08.74282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:01:07.130181Z","time spent":"1.612630195s","remote":"127.0.0.1:49322","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-07-17T16:01:08.742818Z","caller":"traceutil/trace.go:171","msg":"trace[1725528226] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1386; }","duration":"1.57822296s","start":"2026-07-17T16:01:07.164578Z","end":"2026-07-17T16:01:08.742801Z","steps":["trace[1725528226] 'agreement among raft nodes before linearized reading' (duration: 1.578026084s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:01:08.742832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.153814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"warn","ts":"2026-07-17T16:01:08.742858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:01:07.16457Z","time spent":"1.578274952s","remote":"127.0.0.1:49322","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-07-17T16:01:08.742892Z","caller":"traceutil/trace.go:171","msg":"trace[1828923926] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1386; }","duration":"399.237647ms","start":"2026-07-17T16:01:08.343633Z","end":"2026-07-17T16:01:08.74287Z","steps":["trace[1828923926] 'agreement among raft nodes before linearized reading' (duration: 399.138694ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:01:08.742728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.576685037s","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-07-17T16:01:08.742916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.807721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-17T16:01:08.742931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:01:08.343613Z","time spent":"399.309418ms","remote":"127.0.0.1:49322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-17T16:01:08.742958Z","caller":"traceutil/trace.go:171","msg":"trace[1264091485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1386; }","duration":"1.576959455s","start":"2026-07-17T16:01:07.165988Z","end":"2026-07-17T16:01:08.742947Z","steps":["trace[1264091485] 'agreement among raft nodes before linearized reading' (duration: 1.576593004s)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:01:08.742973Z","caller":"traceutil/trace.go:171","msg":"trace[1029977765] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1386; }","duration":"793.901954ms","start":"2026-07-17T16:01:07.949055Z","end":"2026-07-17T16:01:08.742957Z","steps":["trace[1029977765] 'agreement among raft nodes before linearized reading' (duration: 793.736659ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:01:08.742982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.625901258s","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:530"} {"level":"warn","ts":"2026-07-17T16:01:08.742992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:01:07.165974Z","time spent":"1.577007546s","remote":"127.0.0.1:49322","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-07-17T16:01:08.743009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:01:07.949033Z","time spent":"793.968535ms","remote":"127.0.0.1:49322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T16:01:08.743057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.684429119s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-17T16:01:08.743014Z","caller":"traceutil/trace.go:171","msg":"trace[947787062] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1386; }","duration":"1.625977149s","start":"2026-07-17T16:01:07.117027Z","end":"2026-07-17T16:01:08.743005Z","steps":["trace[947787062] 'agreement among raft nodes before linearized reading' (duration: 1.625884587s)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:01:08.743101Z","caller":"traceutil/trace.go:171","msg":"trace[571820384] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1386; }","duration":"1.684497211s","start":"2026-07-17T16:01:07.058589Z","end":"2026-07-17T16:01:08.743087Z","steps":["trace[571820384] 'agreement among raft nodes before linearized reading' (duration: 1.684415478s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T16:01:08.743108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:01:07.117002Z","time spent":"1.626092573s","remote":"127.0.0.1:49322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":552,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-17T16:01:08.743132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T16:01:07.058574Z","time spent":"1.684549872s","remote":"127.0.0.1:49322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-17T16:01:18.942944Z","caller":"traceutil/trace.go:171","msg":"trace[1583199097] transaction","detail":"{read_only:false; response_revision:1440; number_of_response:1; }","duration":"111.926729ms","start":"2026-07-17T16:01:18.831001Z","end":"2026-07-17T16:01:18.942928Z","steps":["trace[1583199097] 'process raft request' (duration: 111.883708ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:01:18.943052Z","caller":"traceutil/trace.go:171","msg":"trace[261937633] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"112.064353ms","start":"2026-07-17T16:01:18.830947Z","end":"2026-07-17T16:01:18.943011Z","steps":["trace[261937633] 'process raft request' (duration: 84.911872ms)","trace[261937633] 'compare' (duration: 26.932205ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T16:01:18.943137Z","caller":"traceutil/trace.go:171","msg":"trace[587371332] transaction","detail":"{read_only:false; response_revision:1441; number_of_response:1; }","duration":"111.450915ms","start":"2026-07-17T16:01:18.831667Z","end":"2026-07-17T16:01:18.943118Z","steps":["trace[587371332] 'process raft request' (duration: 111.241119ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:02:01.375699Z","caller":"traceutil/trace.go:171","msg":"trace[353369530] transaction","detail":"{read_only:false; response_revision:1629; number_of_response:1; }","duration":"116.431515ms","start":"2026-07-17T16:02:01.259241Z","end":"2026-07-17T16:02:01.375672Z","steps":["trace[353369530] 'process raft request' (duration: 116.265931ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:02:01.401321Z","caller":"traceutil/trace.go:171","msg":"trace[1857294212] transaction","detail":"{read_only:false; response_revision:1630; number_of_response:1; }","duration":"140.780298ms","start":"2026-07-17T16:02:01.260514Z","end":"2026-07-17T16:02:01.401294Z","steps":["trace[1857294212] 'process raft request' (duration: 140.552552ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:02:01.401531Z","caller":"traceutil/trace.go:171","msg":"trace[233411957] transaction","detail":"{read_only:false; response_revision:1631; number_of_response:1; }","duration":"140.922162ms","start":"2026-07-17T16:02:01.260592Z","end":"2026-07-17T16:02:01.401514Z","steps":["trace[233411957] 'process raft request' (duration: 140.641604ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:02:01.401647Z","caller":"traceutil/trace.go:171","msg":"trace[1224268845] transaction","detail":"{read_only:false; response_revision:1632; number_of_response:1; }","duration":"140.910901ms","start":"2026-07-17T16:02:01.260724Z","end":"2026-07-17T16:02:01.401635Z","steps":["trace[1224268845] 'process raft request' (duration: 140.709936ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:02:01.401932Z","caller":"traceutil/trace.go:171","msg":"trace[973497788] transaction","detail":"{read_only:false; response_revision:1634; number_of_response:1; }","duration":"140.549661ms","start":"2026-07-17T16:02:01.261371Z","end":"2026-07-17T16:02:01.401921Z","steps":["trace[973497788] 'process raft request' (duration: 140.49931ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:02:01.402033Z","caller":"traceutil/trace.go:171","msg":"trace[1161557947] transaction","detail":"{read_only:false; response_revision:1633; number_of_response:1; }","duration":"141.085726ms","start":"2026-07-17T16:02:01.260939Z","end":"2026-07-17T16:02:01.402025Z","steps":["trace[1161557947] 'process raft request' (duration: 140.655934ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T16:02:29.675014Z","caller":"traceutil/trace.go:171","msg":"trace[1116442906] transaction","detail":"{read_only:false; response_revision:1763; number_of_response:1; }","duration":"117.500882ms","start":"2026-07-17T16:02:29.557493Z","end":"2026-07-17T16:02:29.674994Z","steps":["trace[1116442906] 'process raft request' (duration: 100.870079ms)","trace[1116442906] 'compare' (duration: 16.261252ms)"],"step_count":2}