{"level":"warn","ts":"2026-07-07T13:09:25.300495Z","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-07T13:09:25.300608Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.96: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.96:2380","--initial-cluster=instance=https://199.19.213.96:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.96:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.96: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-07T13:09:25.300677Z","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-07T13:09:25.300689Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.96:2380"]} {"level":"info","ts":"2026-07-07T13:09:25.300708Z","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-07T13:09:25.301414Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.96:2379"]} {"level":"info","ts":"2026-07-07T13:09:25.30152Z","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.96:2380"],"listen-peer-urls":["https://199.19.213.96:2380"],"advertise-client-urls":["https://199.19.213.96:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.96:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.96: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-07T13:09:25.304107Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.310528ms"} {"level":"info","ts":"2026-07-07T13:09:25.309279Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"59b38ffafb07452b","cluster-id":"f6b359b7e8f93d01"} {"level":"info","ts":"2026-07-07T13:09:25.309342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b switched to configuration voters=()"} {"level":"info","ts":"2026-07-07T13:09:25.309367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b became follower at term 0"} {"level":"info","ts":"2026-07-07T13:09:25.309384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 59b38ffafb07452b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-07T13:09:25.309399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b became follower at term 1"} {"level":"info","ts":"2026-07-07T13:09:25.309436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b switched to configuration voters=(6463668198322947371)"} {"level":"warn","ts":"2026-07-07T13:09:25.314595Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-07T13:09:25.317287Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-07T13:09:25.318617Z","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-07T13:09:25.321431Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"59b38ffafb07452b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-07T13:09:25.321708Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"59b38ffafb07452b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-07T13:09:25.321962Z","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-07T13:09:25.322021Z","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-07T13:09:25.322034Z","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-07T13:09:25.322696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b switched to configuration voters=(6463668198322947371)"} {"level":"info","ts":"2026-07-07T13:09:25.322829Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f6b359b7e8f93d01","local-member-id":"59b38ffafb07452b","added-peer-id":"59b38ffafb07452b","added-peer-peer-urls":["https://199.19.213.96:2380"]} {"level":"info","ts":"2026-07-07T13:09:25.329267Z","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-07T13:09:25.329423Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.96:2380"} {"level":"info","ts":"2026-07-07T13:09:25.329505Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.96:2380"} {"level":"info","ts":"2026-07-07T13:09:25.329567Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"59b38ffafb07452b","initial-advertise-peer-urls":["https://199.19.213.96:2380"],"listen-peer-urls":["https://199.19.213.96:2380"],"advertise-client-urls":["https://199.19.213.96:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.96:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-07T13:09:25.329619Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-07T13:09:26.110281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b is starting a new election at term 1"} {"level":"info","ts":"2026-07-07T13:09:26.110366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b became pre-candidate at term 1"} {"level":"info","ts":"2026-07-07T13:09:26.110419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b received MsgPreVoteResp from 59b38ffafb07452b at term 1"} {"level":"info","ts":"2026-07-07T13:09:26.110441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b became candidate at term 2"} {"level":"info","ts":"2026-07-07T13:09:26.110453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b received MsgVoteResp from 59b38ffafb07452b at term 2"} {"level":"info","ts":"2026-07-07T13:09:26.110469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b became leader at term 2"} {"level":"info","ts":"2026-07-07T13:09:26.110518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 59b38ffafb07452b elected leader 59b38ffafb07452b at term 2"} {"level":"info","ts":"2026-07-07T13:09:26.119387Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T13:09:26.120193Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"59b38ffafb07452b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.96:2379]}","request-path":"/0/members/59b38ffafb07452b/attributes","cluster-id":"f6b359b7e8f93d01","publish-timeout":"7s"} {"level":"info","ts":"2026-07-07T13:09:26.120361Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-07T13:09:26.120404Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-07T13:09:26.120659Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-07T13:09:26.120694Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-07T13:09:26.12085Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f6b359b7e8f93d01","local-member-id":"59b38ffafb07452b","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T13:09:26.120999Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T13:09:26.121048Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T13:09:26.123746Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-07T13:09:26.124081Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.96:2379"} {"level":"info","ts":"2026-07-07T13:09:27.682875Z","caller":"traceutil/trace.go:171","msg":"trace[525222169] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"292.377907ms","start":"2026-07-07T13:09:27.390474Z","end":"2026-07-07T13:09:27.682852Z","steps":["trace[525222169] 'read index received' (duration: 292.203682ms)","trace[525222169] 'applied index is now lower than readState.Index' (duration: 173.215µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T13:09:27.682934Z","caller":"traceutil/trace.go:171","msg":"trace[1964694642] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"293.254359ms","start":"2026-07-07T13:09:27.389639Z","end":"2026-07-07T13:09:27.682893Z","steps":["trace[1964694642] 'process raft request' (duration: 293.008773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:09:27.683381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.843868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-07T13:09:27.683503Z","caller":"traceutil/trace.go:171","msg":"trace[412981994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:2; }","duration":"293.005132ms","start":"2026-07-07T13:09:27.390471Z","end":"2026-07-07T13:09:27.683476Z","steps":["trace[412981994] 'agreement among raft nodes before linearized reading' (duration: 292.534161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:09:28.183887Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4984252495658794368,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-07T13:09:28.52937Z","caller":"traceutil/trace.go:171","msg":"trace[584507509] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"1.073870739s","start":"2026-07-07T13:09:27.455464Z","end":"2026-07-07T13:09:28.529335Z","steps":["trace[584507509] 'process raft request' (duration: 1.068088383s)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:09:28.529412Z","caller":"traceutil/trace.go:171","msg":"trace[995256604] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.072838073s","start":"2026-07-07T13:09:27.456504Z","end":"2026-07-07T13:09:28.529342Z","steps":["trace[995256604] 'process raft request' (duration: 1.072697369s)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:09:28.529428Z","caller":"traceutil/trace.go:171","msg":"trace[1271822646] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.072886235s","start":"2026-07-07T13:09:27.4565Z","end":"2026-07-07T13:09:28.529387Z","steps":["trace[1271822646] 'process raft request' (duration: 1.072614668s)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:09:28.529669Z","caller":"traceutil/trace.go:171","msg":"trace[1749553511] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.072890075s","start":"2026-07-07T13:09:27.456765Z","end":"2026-07-07T13:09:28.529655Z","steps":["trace[1749553511] 'process raft request' (duration: 1.072497865s)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:09:28.529487Z","caller":"traceutil/trace.go:171","msg":"trace[1075169210] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.072832503s","start":"2026-07-07T13:09:27.456645Z","end":"2026-07-07T13:09:28.529477Z","steps":["trace[1075169210] 'process raft request' (duration: 1.072586687s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:09:28.530392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:09:27.455441Z","time spent":"1.074156506s","remote":"127.0.0.1:59234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T13:09:28.530537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:09:27.456484Z","time spent":"1.073241263s","remote":"127.0.0.1:59306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T13:09:28.5308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:09:27.456488Z","time spent":"1.073255904s","remote":"127.0.0.1:59306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T13:09:28.531009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:09:27.456755Z","time spent":"1.073081509s","remote":"127.0.0.1:59306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T13:09:28.531109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:09:27.456636Z","time spent":"1.073235103s","remote":"127.0.0.1:59306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T13:09:28.532021Z","caller":"traceutil/trace.go:171","msg":"trace[2117039428] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:5; }","duration":"849.040843ms","start":"2026-07-07T13:09:27.682959Z","end":"2026-07-07T13:09:28.531999Z","steps":["trace[2117039428] 'read index received' (duration: 840.521809ms)","trace[2117039428] 'applied index is now lower than readState.Index' (duration: 8.518014ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T13:09:28.532304Z","caller":"traceutil/trace.go:171","msg":"trace[265647528] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.075454729s","start":"2026-07-07T13:09:27.456837Z","end":"2026-07-07T13:09:28.532292Z","steps":["trace[265647528] 'process raft request' (duration: 1.074948797s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:09:28.532426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:09:27.456828Z","time spent":"1.075543111s","remote":"127.0.0.1:59306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T13:09:28.532701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.078847645s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.96\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-07T13:09:28.532747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.130790821s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-07T13:09:28.532754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.71895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-07T13:09:28.532783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.142141017s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-07T13:09:28.532797Z","caller":"traceutil/trace.go:171","msg":"trace[573860971] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"1.130870533s","start":"2026-07-07T13:09:27.401912Z","end":"2026-07-07T13:09:28.532783Z","steps":["trace[573860971] 'agreement among raft nodes before linearized reading' (duration: 1.130786911s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:09:28.532823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:09:27.401902Z","time spent":"1.130916525s","remote":"127.0.0.1:59188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-07-07T13:09:28.532833Z","caller":"traceutil/trace.go:171","msg":"trace[1793211680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:10; }","duration":"1.142185909s","start":"2026-07-07T13:09:27.390625Z","end":"2026-07-07T13:09:28.532811Z","steps":["trace[1793211680] 'agreement among raft nodes before linearized reading' (duration: 1.142138487s)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:09:28.532835Z","caller":"traceutil/trace.go:171","msg":"trace[1375484002] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"793.815592ms","start":"2026-07-07T13:09:27.738975Z","end":"2026-07-07T13:09:28.532791Z","steps":["trace[1375484002] 'agreement among raft nodes before linearized reading' (duration: 793.70651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:09:28.532868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:09:27.390621Z","time spent":"1.14224028s","remote":"127.0.0.1:59068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-07T13:09:28.532875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.281976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-07T13:09:28.53299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.510552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-07T13:09:28.53306Z","caller":"traceutil/trace.go:171","msg":"trace[375814916] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.066650788s","start":"2026-07-07T13:09:27.466402Z","end":"2026-07-07T13:09:28.533053Z","steps":["trace[375814916] 'process raft request' (duration: 1.065478548s)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:09:28.533071Z","caller":"traceutil/trace.go:171","msg":"trace[921933117] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.052371119s","start":"2026-07-07T13:09:27.480669Z","end":"2026-07-07T13:09:28.53304Z","steps":["trace[921933117] 'process raft request' (duration: 1.051289241s)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:09:28.533059Z","caller":"traceutil/trace.go:171","msg":"trace[1981174822] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:10; }","duration":"178.628305ms","start":"2026-07-07T13:09:28.35442Z","end":"2026-07-07T13:09:28.533048Z","steps":["trace[1981174822] 'agreement among raft nodes before linearized reading' (duration: 178.543373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:09:28.533106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:09:27.466392Z","time spent":"1.066692079s","remote":"127.0.0.1:58946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T13:09:28.533119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02740039s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-07T13:09:28.533069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.307213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-07T13:09:28.533235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.076872785s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-07T13:09:28.53328Z","caller":"traceutil/trace.go:171","msg":"trace[505264387] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"1.076934997s","start":"2026-07-07T13:09:27.456335Z","end":"2026-07-07T13:09:28.53327Z","steps":["trace[505264387] 'agreement among raft nodes before linearized reading' (duration: 1.076881796s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:09:28.533322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:09:27.456324Z","time spent":"1.076983368s","remote":"127.0.0.1:58838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-07-07T13:09:28.532834Z","caller":"traceutil/trace.go:171","msg":"trace[623925404] range","detail":"{range_begin:/registry/masterleases/199.19.213.96; range_end:; response_count:0; response_revision:10; }","duration":"1.078973718s","start":"2026-07-07T13:09:27.453794Z","end":"2026-07-07T13:09:28.532768Z","steps":["trace[623925404] 'agreement among raft nodes before linearized reading' (duration: 1.078853995s)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:09:28.53333Z","caller":"traceutil/trace.go:171","msg":"trace[1865709249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10; }","duration":"150.528008ms","start":"2026-07-07T13:09:28.382731Z","end":"2026-07-07T13:09:28.533259Z","steps":["trace[1865709249] 'agreement among raft nodes before linearized reading' (duration: 150.277442ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:09:28.533151Z","caller":"traceutil/trace.go:171","msg":"trace[1938203261] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.027463482s","start":"2026-07-07T13:09:27.505678Z","end":"2026-07-07T13:09:28.533142Z","steps":["trace[1938203261] 'agreement among raft nodes before linearized reading' (duration: 1.027414581s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:09:28.533431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:09:27.505668Z","time spent":"1.027758249s","remote":"127.0.0.1:58880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-07T13:09:28.533391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:09:27.453772Z","time spent":"1.079586225s","remote":"127.0.0.1:58778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.19.213.96\" "} {"level":"warn","ts":"2026-07-07T13:09:28.532736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.142229499s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-07T13:09:28.533676Z","caller":"traceutil/trace.go:171","msg":"trace[335881366] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:10; }","duration":"1.143206964s","start":"2026-07-07T13:09:27.390455Z","end":"2026-07-07T13:09:28.533662Z","steps":["trace[335881366] 'agreement among raft nodes before linearized reading' (duration: 1.142234189s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:09:28.533726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:09:27.390429Z","time spent":"1.143287776s","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":0,"response size":27,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-07-07T13:09:28.533001Z","caller":"traceutil/trace.go:171","msg":"trace[1653882139] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:10; }","duration":"178.454471ms","start":"2026-07-07T13:09:28.354509Z","end":"2026-07-07T13:09:28.532964Z","steps":["trace[1653882139] 'agreement among raft nodes before linearized reading' (duration: 178.275155ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:09:28.532876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:09:27.738949Z","time spent":"793.917136ms","remote":"127.0.0.1:58880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-07T13:09:28.533186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.007936951s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-07T13:09:28.534126Z","caller":"traceutil/trace.go:171","msg":"trace[222676214] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.008873094s","start":"2026-07-07T13:09:27.525243Z","end":"2026-07-07T13:09:28.534116Z","steps":["trace[222676214] 'agreement among raft nodes before linearized reading' (duration: 1.007926981s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:09:28.534166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:09:27.525224Z","time spent":"1.008932735s","remote":"127.0.0.1:58880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-07T13:09:28.533164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:09:27.480654Z","time spent":"1.052471342s","remote":"127.0.0.1:59056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T13:09:28.536674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080331402s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-07T13:09:28.536753Z","caller":"traceutil/trace.go:171","msg":"trace[3448239] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.080434064s","start":"2026-07-07T13:09:27.456305Z","end":"2026-07-07T13:09:28.536739Z","steps":["trace[3448239] 'agreement among raft nodes before linearized reading' (duration: 1.076251159s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:09:28.536788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:09:27.456296Z","time spent":"1.080483896s","remote":"127.0.0.1:58880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-07T13:10:02.524828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.357546ms","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-07T13:10:02.524922Z","caller":"traceutil/trace.go:171","msg":"trace[939889732] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:453; }","duration":"164.510099ms","start":"2026-07-07T13:10:02.360387Z","end":"2026-07-07T13:10:02.524897Z","steps":["trace[939889732] 'range keys from in-memory index tree' (duration: 164.216652ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:10:04.41088Z","caller":"traceutil/trace.go:171","msg":"trace[884129002] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"290.210343ms","start":"2026-07-07T13:10:04.120647Z","end":"2026-07-07T13:10:04.410858Z","steps":["trace[884129002] 'process raft request' (duration: 290.059809ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:10:04.797564Z","caller":"traceutil/trace.go:171","msg":"trace[1189039959] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"250.104703ms","start":"2026-07-07T13:10:04.547423Z","end":"2026-07-07T13:10:04.797528Z","steps":["trace[1189039959] 'process raft request' (duration: 249.865807ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:10:04.805826Z","caller":"traceutil/trace.go:171","msg":"trace[911161886] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"169.6966ms","start":"2026-07-07T13:10:04.636094Z","end":"2026-07-07T13:10:04.80579Z","steps":["trace[911161886] 'process raft request' (duration: 169.216078ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:10:04.805858Z","caller":"traceutil/trace.go:171","msg":"trace[1941701722] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"181.993009ms","start":"2026-07-07T13:10:04.623833Z","end":"2026-07-07T13:10:04.805826Z","steps":["trace[1941701722] 'process raft request' (duration: 181.24368ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:10:04.805979Z","caller":"traceutil/trace.go:171","msg":"trace[316392697] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"176.661585ms","start":"2026-07-07T13:10:04.62929Z","end":"2026-07-07T13:10:04.805951Z","steps":["trace[316392697] 'process raft request' (duration: 175.949897ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:10:04.806148Z","caller":"traceutil/trace.go:171","msg":"trace[1679286587] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"165.439352ms","start":"2026-07-07T13:10:04.640693Z","end":"2026-07-07T13:10:04.806132Z","steps":["trace[1679286587] 'process raft request' (duration: 164.699134ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:10:04.806108Z","caller":"traceutil/trace.go:171","msg":"trace[1511289472] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"166.042388ms","start":"2026-07-07T13:10:04.639987Z","end":"2026-07-07T13:10:04.806029Z","steps":["trace[1511289472] 'process raft request' (duration: 165.367831ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:10:04.806188Z","caller":"traceutil/trace.go:171","msg":"trace[961493413] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"175.332972ms","start":"2026-07-07T13:10:04.630838Z","end":"2026-07-07T13:10:04.806171Z","steps":["trace[961493413] 'process raft request' (duration: 174.43465ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:10:04.806256Z","caller":"traceutil/trace.go:171","msg":"trace[1088214095] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"161.181926ms","start":"2026-07-07T13:10:04.645065Z","end":"2026-07-07T13:10:04.806247Z","steps":["trace[1088214095] 'process raft request' (duration: 160.442368ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:10:04.806314Z","caller":"traceutil/trace.go:171","msg":"trace[1037951430] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"156.029887ms","start":"2026-07-07T13:10:04.650274Z","end":"2026-07-07T13:10:04.806304Z","steps":["trace[1037951430] 'process raft request' (duration: 155.284078ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:10:04.806511Z","caller":"traceutil/trace.go:171","msg":"trace[1474197125] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"139.262454ms","start":"2026-07-07T13:10:04.667236Z","end":"2026-07-07T13:10:04.806499Z","steps":["trace[1474197125] 'process raft request' (duration: 138.458924ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:10:04.806573Z","caller":"traceutil/trace.go:171","msg":"trace[621466476] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"151.733598ms","start":"2026-07-07T13:10:04.654827Z","end":"2026-07-07T13:10:04.80656Z","steps":["trace[621466476] 'process raft request' (duration: 150.818465ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:10:04.805888Z","caller":"traceutil/trace.go:171","msg":"trace[1171919823] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"160.826757ms","start":"2026-07-07T13:10:04.645041Z","end":"2026-07-07T13:10:04.805868Z","steps":["trace[1171919823] 'process raft request' (duration: 160.387466ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:10:10.900852Z","caller":"traceutil/trace.go:171","msg":"trace[67322851] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"185.491727ms","start":"2026-07-07T13:10:10.715338Z","end":"2026-07-07T13:10:10.900829Z","steps":["trace[67322851] 'process raft request' (duration: 184.532703ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:10:11.413793Z","caller":"traceutil/trace.go:171","msg":"trace[1922215940] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"148.320501ms","start":"2026-07-07T13:10:11.265441Z","end":"2026-07-07T13:10:11.413761Z","steps":["trace[1922215940] 'read index received' (duration: 148.174978ms)","trace[1922215940] 'applied index is now lower than readState.Index' (duration: 144.593µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T13:10:11.413832Z","caller":"traceutil/trace.go:171","msg":"trace[1804523591] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"211.457251ms","start":"2026-07-07T13:10:11.202334Z","end":"2026-07-07T13:10:11.413792Z","steps":["trace[1804523591] 'process raft request' (duration: 211.225155ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:10:11.41409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.63052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vwj7q\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-07T13:10:11.414176Z","caller":"traceutil/trace.go:171","msg":"trace[1898256774] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vwj7q; range_end:; response_count:1; response_revision:593; }","duration":"148.758872ms","start":"2026-07-07T13:10:11.265398Z","end":"2026-07-07T13:10:11.414157Z","steps":["trace[1898256774] 'agreement among raft nodes before linearized reading' (duration: 148.493055ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:10:11.565906Z","caller":"traceutil/trace.go:171","msg":"trace[1847126745] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"177.864245ms","start":"2026-07-07T13:10:11.38802Z","end":"2026-07-07T13:10:11.565884Z","steps":["trace[1847126745] 'process raft request' (duration: 170.09205ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:10:11.592578Z","caller":"traceutil/trace.go:171","msg":"trace[1074819902] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"100.20013ms","start":"2026-07-07T13:10:11.492358Z","end":"2026-07-07T13:10:11.592558Z","steps":["trace[1074819902] 'process raft request' (duration: 100.159859ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:10:11.592837Z","caller":"traceutil/trace.go:171","msg":"trace[1616999981] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"163.040373ms","start":"2026-07-07T13:10:11.429791Z","end":"2026-07-07T13:10:11.592831Z","steps":["trace[1616999981] 'process raft request' (duration: 162.593421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:10:12.713488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.948415ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-07T13:10:12.713587Z","caller":"traceutil/trace.go:171","msg":"trace[895101303] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"332.199738ms","start":"2026-07-07T13:10:12.381372Z","end":"2026-07-07T13:10:12.713572Z","steps":["trace[895101303] 'read index received' (duration: 192.984105ms)","trace[895101303] 'applied index is now lower than readState.Index' (duration: 139.214563ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T13:10:12.713645Z","caller":"traceutil/trace.go:171","msg":"trace[1949416994] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"1.072963217s","start":"2026-07-07T13:10:11.640624Z","end":"2026-07-07T13:10:12.713587Z","steps":["trace[1949416994] 'process raft request' (duration: 933.713033ms)","trace[1949416994] 'compare' (duration: 138.832392ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T13:10:12.71383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.270826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-07T13:10:12.713834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:10:11.640597Z","time spent":"1.073152932s","remote":"127.0.0.1:59136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T13:10:12.713869Z","caller":"traceutil/trace.go:171","msg":"trace[200780023] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:597; }","duration":"267.333876ms","start":"2026-07-07T13:10:12.446526Z","end":"2026-07-07T13:10:12.713859Z","steps":["trace[200780023] 'agreement among raft nodes before linearized reading' (duration: 267.210133ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:10:12.713709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.338712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T13:10:12.714199Z","caller":"traceutil/trace.go:171","msg":"trace[464285203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"332.760953ms","start":"2026-07-07T13:10:12.381322Z","end":"2026-07-07T13:10:12.714083Z","steps":["trace[464285203] 'agreement among raft nodes before linearized reading' (duration: 332.326102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:10:12.714351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:10:12.381302Z","time spent":"332.988108ms","remote":"127.0.0.1:58736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-07T13:10:14.601414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.524055ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-07T13:10:14.601569Z","caller":"traceutil/trace.go:171","msg":"trace[606753684] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"189.261252ms","start":"2026-07-07T13:10:14.412297Z","end":"2026-07-07T13:10:14.601558Z","steps":["trace[606753684] 'process raft request' (duration: 189.19364ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:10:20.560619Z","caller":"traceutil/trace.go:171","msg":"trace[1304084687] transaction","detail":"{read_only:false; number_of_response:0; response_revision:707; }","duration":"111.976368ms","start":"2026-07-07T13:10:20.448618Z","end":"2026-07-07T13:10:20.560595Z","steps":["trace[1304084687] 'process raft request' (duration: 111.886846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:10:28.75759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.417929ms","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-07T13:10:28.757616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.512389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T13:10:28.75761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.485516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-lm9tj\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-07-07T13:10:28.757696Z","caller":"traceutil/trace.go:171","msg":"trace[870084782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:767; }","duration":"377.621431ms","start":"2026-07-07T13:10:28.380055Z","end":"2026-07-07T13:10:28.757677Z","steps":["trace[870084782] 'range keys from in-memory index tree' (duration: 377.413737ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:10:28.757692Z","caller":"traceutil/trace.go:171","msg":"trace[834165687] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:767; }","duration":"670.573773ms","start":"2026-07-07T13:10:28.087095Z","end":"2026-07-07T13:10:28.757669Z","steps":["trace[834165687] 'range keys from in-memory index tree' (duration: 670.176193ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:10:28.757711Z","caller":"traceutil/trace.go:171","msg":"trace[3430982] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-lm9tj; range_end:; response_count:1; response_revision:767; }","duration":"390.64859ms","start":"2026-07-07T13:10:28.367043Z","end":"2026-07-07T13:10:28.757692Z","steps":["trace[3430982] 'range keys from in-memory index tree' (duration: 390.293231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:10:28.757739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:10:28.380044Z","time spent":"377.686413ms","remote":"127.0.0.1:58736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-07T13:10:28.757757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:10:28.087024Z","time spent":"670.718527ms","remote":"127.0.0.1:59068","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-07T13:10:28.757773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:10:28.367019Z","time spent":"390.731551ms","remote":"127.0.0.1:58962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-lm9tj\" "} {"level":"info","ts":"2026-07-07T13:10:28.968199Z","caller":"traceutil/trace.go:171","msg":"trace[1882242401] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"204.363122ms","start":"2026-07-07T13:10:28.76377Z","end":"2026-07-07T13:10:28.968133Z","steps":["trace[1882242401] 'process raft request' (duration: 204.217118ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:10:28.985901Z","caller":"traceutil/trace.go:171","msg":"trace[1713652662] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"218.491667ms","start":"2026-07-07T13:10:28.767377Z","end":"2026-07-07T13:10:28.985868Z","steps":["trace[1713652662] 'process raft request' (duration: 218.224191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:11:04.489505Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4984252495658796384,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-07T13:11:04.753957Z","caller":"traceutil/trace.go:171","msg":"trace[2095297068] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"888.28462ms","start":"2026-07-07T13:11:03.86565Z","end":"2026-07-07T13:11:04.753935Z","steps":["trace[2095297068] 'process raft request' (duration: 888.158917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:11:04.75442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:11:03.865626Z","time spent":"888.435633ms","remote":"127.0.0.1:59068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T13:11:04.775484Z","caller":"traceutil/trace.go:171","msg":"trace[308680099] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"468.876807ms","start":"2026-07-07T13:11:04.306578Z","end":"2026-07-07T13:11:04.775455Z","steps":["trace[308680099] 'process raft request' (duration: 468.635601ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:11:04.775627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:11:04.306553Z","time spent":"468.99465ms","remote":"127.0.0.1:59068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T13:11:05.189383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.996768ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-07T13:11:05.189458Z","caller":"traceutil/trace.go:171","msg":"trace[1178385024] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1017; }","duration":"1.200419965s","start":"2026-07-07T13:11:03.989025Z","end":"2026-07-07T13:11:05.189445Z","steps":["trace[1178385024] 'read index received' (duration: 765.090974ms)","trace[1178385024] 'applied index is now lower than readState.Index' (duration: 435.327861ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T13:11:05.189754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.200726303s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T13:11:05.189776Z","caller":"traceutil/trace.go:171","msg":"trace[1329674492] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:985; }","duration":"1.200782124s","start":"2026-07-07T13:11:03.988986Z","end":"2026-07-07T13:11:05.189769Z","steps":["trace[1329674492] 'agreement among raft nodes before linearized reading' (duration: 1.200731292s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:11:05.189799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:11:03.988972Z","time spent":"1.200819965s","remote":"127.0.0.1:59198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-07-07T13:11:05.190001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.785661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T13:11:05.190073Z","caller":"traceutil/trace.go:171","msg":"trace[1993427050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:985; }","duration":"807.903573ms","start":"2026-07-07T13:11:04.382151Z","end":"2026-07-07T13:11:05.190055Z","steps":["trace[1993427050] 'agreement among raft nodes before linearized reading' (duration: 807.79209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:11:05.190115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:11:04.382118Z","time spent":"807.988805ms","remote":"127.0.0.1:58736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-07T13:11:14.933321Z","caller":"traceutil/trace.go:171","msg":"trace[338204045] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"131.75254ms","start":"2026-07-07T13:11:14.801537Z","end":"2026-07-07T13:11:14.933289Z","steps":["trace[338204045] 'process raft request' (duration: 82.733046ms)","trace[338204045] 'compare' (duration: 48.869201ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T13:11:15.500708Z","caller":"traceutil/trace.go:171","msg":"trace[1864545011] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"115.907445ms","start":"2026-07-07T13:11:15.384772Z","end":"2026-07-07T13:11:15.50068Z","steps":["trace[1864545011] 'process raft request' (duration: 74.790738ms)","trace[1864545011] 'compare' (duration: 41.031854ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T13:11:27.701723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.605664ms","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-07T13:11:27.701811Z","caller":"traceutil/trace.go:171","msg":"trace[1209525449] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1057; }","duration":"199.731707ms","start":"2026-07-07T13:11:27.502061Z","end":"2026-07-07T13:11:27.701792Z","steps":["trace[1209525449] 'range keys from in-memory index tree' (duration: 199.404568ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:11:27.701978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.116942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-07T13:11:27.702043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.907518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-07T13:11:27.702062Z","caller":"traceutil/trace.go:171","msg":"trace[1809601086] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1057; }","duration":"187.250145ms","start":"2026-07-07T13:11:27.514791Z","end":"2026-07-07T13:11:27.702041Z","steps":["trace[1809601086] 'count revisions from in-memory index tree' (duration: 187.00201ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:11:27.70209Z","caller":"traceutil/trace.go:171","msg":"trace[1573004998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1057; }","duration":"141.020971ms","start":"2026-07-07T13:11:27.561056Z","end":"2026-07-07T13:11:27.702077Z","steps":["trace[1573004998] 'range keys from in-memory index tree' (duration: 140.761674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:11:27.70218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.453681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T13:11:27.702272Z","caller":"traceutil/trace.go:171","msg":"trace[563729787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1057; }","duration":"320.584864ms","start":"2026-07-07T13:11:27.381662Z","end":"2026-07-07T13:11:27.702247Z","steps":["trace[563729787] 'range keys from in-memory index tree' (duration: 320.325518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:11:27.702306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.510868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-07T13:11:27.702316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:11:27.381641Z","time spent":"320.666406ms","remote":"127.0.0.1:58736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-07T13:11:27.702365Z","caller":"traceutil/trace.go:171","msg":"trace[484654871] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1057; }","duration":"281.598961ms","start":"2026-07-07T13:11:27.420753Z","end":"2026-07-07T13:11:27.702352Z","steps":["trace[484654871] 'range keys from in-memory index tree' (duration: 281.389446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:11:27.702549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.779785ms","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-07T13:11:27.702586Z","caller":"traceutil/trace.go:171","msg":"trace[916438769] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1057; }","duration":"295.861856ms","start":"2026-07-07T13:11:27.406712Z","end":"2026-07-07T13:11:27.702574Z","steps":["trace[916438769] 'range keys from in-memory index tree' (duration: 295.639622ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:11:28.036359Z","caller":"traceutil/trace.go:171","msg":"trace[337156345] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"329.080607ms","start":"2026-07-07T13:11:27.707248Z","end":"2026-07-07T13:11:28.036329Z","steps":["trace[337156345] 'process raft request' (duration: 328.871982ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:11:28.036574Z","caller":"traceutil/trace.go:171","msg":"trace[696474606] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"329.284251ms","start":"2026-07-07T13:11:27.70726Z","end":"2026-07-07T13:11:28.036544Z","steps":["trace[696474606] 'process raft request' (duration: 329.026915ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:11:28.036604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:11:27.707228Z","time spent":"329.290402ms","remote":"127.0.0.1:59068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T13:11:28.036753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:11:27.707242Z","time spent":"329.407465ms","remote":"127.0.0.1:59068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T13:11:28.041643Z","caller":"traceutil/trace.go:171","msg":"trace[1301216218] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1095; }","duration":"266.150035ms","start":"2026-07-07T13:11:27.775476Z","end":"2026-07-07T13:11:28.041626Z","steps":["trace[1301216218] 'read index received' (duration: 260.697519ms)","trace[1301216218] 'applied index is now lower than readState.Index' (duration: 5.451496ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T13:11:28.041786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.30737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T13:11:28.041835Z","caller":"traceutil/trace.go:171","msg":"trace[11341295] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1061; }","duration":"266.398631ms","start":"2026-07-07T13:11:27.775427Z","end":"2026-07-07T13:11:28.041826Z","steps":["trace[11341295] 'agreement among raft nodes before linearized reading' (duration: 266.265768ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:11:28.042133Z","caller":"traceutil/trace.go:171","msg":"trace[172414390] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"334.7945ms","start":"2026-07-07T13:11:27.707328Z","end":"2026-07-07T13:11:28.042122Z","steps":["trace[172414390] 'process raft request' (duration: 334.161494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:11:28.042212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:11:27.707301Z","time spent":"334.864701ms","remote":"127.0.0.1:59068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T13:11:28.042354Z","caller":"traceutil/trace.go:171","msg":"trace[1443522912] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"334.700537ms","start":"2026-07-07T13:11:27.707644Z","end":"2026-07-07T13:11:28.042345Z","steps":["trace[1443522912] 'process raft request' (duration: 333.947188ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:11:28.042411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:11:27.707633Z","time spent":"334.749678ms","remote":"127.0.0.1:59068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T13:11:29.277074Z","caller":"traceutil/trace.go:171","msg":"trace[1733500588] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"218.623899ms","start":"2026-07-07T13:11:29.058422Z","end":"2026-07-07T13:11:29.277046Z","steps":["trace[1733500588] 'process raft request' (duration: 207.604304ms)","trace[1733500588] 'compare' (duration: 10.926373ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T13:11:30.196871Z","caller":"traceutil/trace.go:171","msg":"trace[439191340] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1103; }","duration":"150.614941ms","start":"2026-07-07T13:11:30.046232Z","end":"2026-07-07T13:11:30.196847Z","steps":["trace[439191340] 'read index received' (duration: 150.464586ms)","trace[439191340] 'applied index is now lower than readState.Index' (duration: 149.295µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T13:11:30.196894Z","caller":"traceutil/trace.go:171","msg":"trace[1374817582] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"151.161255ms","start":"2026-07-07T13:11:30.045711Z","end":"2026-07-07T13:11:30.196873Z","steps":["trace[1374817582] 'process raft request' (duration: 151.013421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:11:30.197237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.97318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-07T13:11:30.197328Z","caller":"traceutil/trace.go:171","msg":"trace[945200197] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1065; }","duration":"151.096982ms","start":"2026-07-07T13:11:30.04621Z","end":"2026-07-07T13:11:30.197307Z","steps":["trace[945200197] 'agreement among raft nodes before linearized reading' (duration: 150.764704ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:11:30.206353Z","caller":"traceutil/trace.go:171","msg":"trace[1648412396] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"158.939849ms","start":"2026-07-07T13:11:30.047395Z","end":"2026-07-07T13:11:30.206335Z","steps":["trace[1648412396] 'process raft request' (duration: 158.708393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:11:30.206495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.043116ms","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-07T13:11:30.206592Z","caller":"traceutil/trace.go:171","msg":"trace[551197849] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1066; }","duration":"160.244461ms","start":"2026-07-07T13:11:30.046331Z","end":"2026-07-07T13:11:30.206576Z","steps":["trace[551197849] 'agreement among raft nodes before linearized reading' (duration: 159.903502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:11:30.633118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.154843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T13:11:30.633194Z","caller":"traceutil/trace.go:171","msg":"trace[1850498295] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1068; }","duration":"189.317256ms","start":"2026-07-07T13:11:30.443859Z","end":"2026-07-07T13:11:30.633176Z","steps":["trace[1850498295] 'count revisions from in-memory index tree' (duration: 189.06423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:11:46.840888Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4984252495658796835,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T13:11:47.341975Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4984252495658796835,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T13:11:47.497452Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.214182027s","expected-duration":"1s"} {"level":"info","ts":"2026-07-07T13:11:47.497831Z","caller":"traceutil/trace.go:171","msg":"trace[1107656226] linearizableReadLoop","detail":"{readStateIndex:1213; appliedIndex:1212; }","duration":"1.157174305s","start":"2026-07-07T13:11:46.340635Z","end":"2026-07-07T13:11:47.49781Z","steps":["trace[1107656226] 'read index received' (duration: 1.157056362s)","trace[1107656226] 'applied index is now lower than readState.Index' (duration: 117.273µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T13:11:47.497945Z","caller":"traceutil/trace.go:171","msg":"trace[1837100912] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"1.214783812s","start":"2026-07-07T13:11:46.28314Z","end":"2026-07-07T13:11:47.497924Z","steps":["trace[1837100912] 'process raft request' (duration: 1.214522506s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:11:47.498051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157408861s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-07T13:11:47.498088Z","caller":"traceutil/trace.go:171","msg":"trace[27671647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1169; }","duration":"1.157500593s","start":"2026-07-07T13:11:46.340579Z","end":"2026-07-07T13:11:47.49808Z","steps":["trace[27671647] 'agreement among raft nodes before linearized reading' (duration: 1.157336098s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:11:47.498065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:11:46.283116Z","time spent":"1.214889654s","remote":"127.0.0.1:59068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T13:11:47.498113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:11:46.340563Z","time spent":"1.157542904s","remote":"127.0.0.1:59068","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-07T13:11:47.513191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.169990035s","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-07T13:11:47.513254Z","caller":"traceutil/trace.go:171","msg":"trace[1810269144] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1170; }","duration":"1.170088967s","start":"2026-07-07T13:11:46.343153Z","end":"2026-07-07T13:11:47.513242Z","steps":["trace[1810269144] 'agreement among raft nodes before linearized reading' (duration: 1.169925733s)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:11:47.513255Z","caller":"traceutil/trace.go:171","msg":"trace[1632982435] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"111.041893ms","start":"2026-07-07T13:11:47.402193Z","end":"2026-07-07T13:11:47.513235Z","steps":["trace[1632982435] 'process raft request' (duration: 110.798767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:11:47.513282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:11:46.343136Z","time spent":"1.170138758s","remote":"127.0.0.1:59068","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-07T13:11:47.51326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.594809ms","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-07T13:11:47.513194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.279688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T13:11:47.513359Z","caller":"traceutil/trace.go:171","msg":"trace[438958149] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1170; }","duration":"542.714682ms","start":"2026-07-07T13:11:46.97062Z","end":"2026-07-07T13:11:47.513334Z","steps":["trace[438958149] 'agreement among raft nodes before linearized reading' (duration: 542.548178ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:11:47.513392Z","caller":"traceutil/trace.go:171","msg":"trace[955570565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1170; }","duration":"131.506193ms","start":"2026-07-07T13:11:47.381854Z","end":"2026-07-07T13:11:47.51336Z","steps":["trace[955570565] 'agreement among raft nodes before linearized reading' (duration: 131.290378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:11:47.513424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:11:46.970579Z","time spent":"542.827494ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-07-07T13:11:47.513477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.321535ms","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-07T13:11:47.513574Z","caller":"traceutil/trace.go:171","msg":"trace[717872350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1170; }","duration":"420.471629ms","start":"2026-07-07T13:11:47.093082Z","end":"2026-07-07T13:11:47.513554Z","steps":["trace[717872350] 'agreement among raft nodes before linearized reading' (duration: 420.182422ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:11:47.513626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:11:47.093061Z","time spent":"420.555311ms","remote":"127.0.0.1:59068","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-07T13:11:47.513719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.169913373s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-07T13:11:47.513782Z","caller":"traceutil/trace.go:171","msg":"trace[118481595] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1170; }","duration":"1.169986664s","start":"2026-07-07T13:11:46.343785Z","end":"2026-07-07T13:11:47.513772Z","steps":["trace[118481595] 'agreement among raft nodes before linearized reading' (duration: 1.169690247s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:11:47.513855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:11:46.34378Z","time spent":"1.170033355s","remote":"127.0.0.1:59068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-07T13:12:01.757859Z","caller":"traceutil/trace.go:171","msg":"trace[602287494] linearizableReadLoop","detail":"{readStateIndex:1263; appliedIndex:1262; }","duration":"114.486058ms","start":"2026-07-07T13:12:01.643353Z","end":"2026-07-07T13:12:01.757839Z","steps":["trace[602287494] 'read index received' (duration: 114.307034ms)","trace[602287494] 'applied index is now lower than readState.Index' (duration: 178.134µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T13:12:01.757992Z","caller":"traceutil/trace.go:171","msg":"trace[1107041016] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"124.313364ms","start":"2026-07-07T13:12:01.63365Z","end":"2026-07-07T13:12:01.757964Z","steps":["trace[1107041016] 'process raft request' (duration: 124.054328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:12:01.75823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.460407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-07T13:12:01.758264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.099288ms","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-07T13:12:01.758292Z","caller":"traceutil/trace.go:171","msg":"trace[1391368198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1216; }","duration":"112.558249ms","start":"2026-07-07T13:12:01.64572Z","end":"2026-07-07T13:12:01.758278Z","steps":["trace[1391368198] 'agreement among raft nodes before linearized reading' (duration: 112.299363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:12:01.758282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.383666ms","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-07T13:12:01.758327Z","caller":"traceutil/trace.go:171","msg":"trace[509070684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1216; }","duration":"112.18273ms","start":"2026-07-07T13:12:01.646128Z","end":"2026-07-07T13:12:01.758311Z","steps":["trace[509070684] 'agreement among raft nodes before linearized reading' (duration: 111.973345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:12:01.758174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.808496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-07T13:12:01.758339Z","caller":"traceutil/trace.go:171","msg":"trace[1748012764] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1216; }","duration":"110.56312ms","start":"2026-07-07T13:12:01.647759Z","end":"2026-07-07T13:12:01.758322Z","steps":["trace[1748012764] 'agreement among raft nodes before linearized reading' (duration: 110.283643ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:12:01.758366Z","caller":"traceutil/trace.go:171","msg":"trace[958912842] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1216; }","duration":"115.037752ms","start":"2026-07-07T13:12:01.643321Z","end":"2026-07-07T13:12:01.758359Z","steps":["trace[958912842] 'agreement among raft nodes before linearized reading' (duration: 114.733034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:12:04.546779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.928772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T13:12:04.546882Z","caller":"traceutil/trace.go:171","msg":"trace[467177032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1248; }","duration":"165.074686ms","start":"2026-07-07T13:12:04.38179Z","end":"2026-07-07T13:12:04.546865Z","steps":["trace[467177032] 'range keys from in-memory index tree' (duration: 164.789118ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:12:05.798618Z","caller":"traceutil/trace.go:171","msg":"trace[183549171] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"188.43266ms","start":"2026-07-07T13:12:05.610163Z","end":"2026-07-07T13:12:05.798596Z","steps":["trace[183549171] 'process raft request' (duration: 188.298306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:12:06.301064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.091699ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T13:12:06.301128Z","caller":"traceutil/trace.go:171","msg":"trace[467209527] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"491.391779ms","start":"2026-07-07T13:12:05.809725Z","end":"2026-07-07T13:12:06.301116Z","steps":["trace[467209527] 'process raft request' (duration: 268.104156ms)","trace[467209527] 'compare' (duration: 222.978784ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T13:12:06.30116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:12:05.809701Z","time spent":"491.44401ms","remote":"127.0.0.1:59068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T13:12:06.310132Z","caller":"traceutil/trace.go:171","msg":"trace[337631348] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"499.995485ms","start":"2026-07-07T13:12:05.810112Z","end":"2026-07-07T13:12:06.310107Z","steps":["trace[337631348] 'process raft request' (duration: 499.837601ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:12:06.310252Z","caller":"traceutil/trace.go:171","msg":"trace[1498268038] linearizableReadLoop","detail":"{readStateIndex:1302; appliedIndex:1299; }","duration":"429.129609ms","start":"2026-07-07T13:12:05.881104Z","end":"2026-07-07T13:12:06.310233Z","steps":["trace[1498268038] 'read index received' (duration: 196.82911ms)","trace[1498268038] 'applied index is now lower than readState.Index' (duration: 232.299109ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T13:12:06.310274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:12:05.810098Z","time spent":"500.102157ms","remote":"127.0.0.1:59068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T13:12:06.310366Z","caller":"traceutil/trace.go:171","msg":"trace[1818934365] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"500.026585ms","start":"2026-07-07T13:12:05.810312Z","end":"2026-07-07T13:12:06.310338Z","steps":["trace[1818934365] 'process raft request' (duration: 499.759749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:12:06.310414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.299973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-07T13:12:06.310446Z","caller":"traceutil/trace.go:171","msg":"trace[1494583663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1254; }","duration":"429.361285ms","start":"2026-07-07T13:12:05.881074Z","end":"2026-07-07T13:12:06.310435Z","steps":["trace[1494583663] 'agreement among raft nodes before linearized reading' (duration: 429.235402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:12:06.310482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:12:05.881058Z","time spent":"429.416956ms","remote":"127.0.0.1:59068","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-07T13:12:06.310507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:12:05.810303Z","time spent":"500.132378ms","remote":"127.0.0.1:59068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T13:12:06.461861Z","caller":"traceutil/trace.go:171","msg":"trace[1600367282] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"147.601217ms","start":"2026-07-07T13:12:06.314222Z","end":"2026-07-07T13:12:06.461823Z","steps":["trace[1600367282] 'process raft request' (duration: 139.456413ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:12:06.905903Z","caller":"traceutil/trace.go:171","msg":"trace[495248968] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"154.193732ms","start":"2026-07-07T13:12:06.751682Z","end":"2026-07-07T13:12:06.905875Z","steps":["trace[495248968] 'process raft request' (duration: 69.08158ms)","trace[495248968] 'compare' (duration: 84.996779ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T13:12:08.028419Z","caller":"traceutil/trace.go:171","msg":"trace[582175652] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"222.650597ms","start":"2026-07-07T13:12:07.805744Z","end":"2026-07-07T13:12:08.028394Z","steps":["trace[582175652] 'process raft request' (duration: 222.501613ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:12:08.037364Z","caller":"traceutil/trace.go:171","msg":"trace[1977482581] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"226.601706ms","start":"2026-07-07T13:12:07.810745Z","end":"2026-07-07T13:12:08.037346Z","steps":["trace[1977482581] 'process raft request' (duration: 226.514484ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:12:17.842559Z","caller":"traceutil/trace.go:171","msg":"trace[1422382042] linearizableReadLoop","detail":"{readStateIndex:1384; appliedIndex:1383; }","duration":"461.674084ms","start":"2026-07-07T13:12:17.380861Z","end":"2026-07-07T13:12:17.842535Z","steps":["trace[1422382042] 'read index received' (duration: 461.446478ms)","trace[1422382042] 'applied index is now lower than readState.Index' (duration: 226.746µs)"],"step_count":2} {"level":"warn","ts":"2026-07-07T13:12:17.842708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.832768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T13:12:17.842741Z","caller":"traceutil/trace.go:171","msg":"trace[1835463187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1334; }","duration":"461.908121ms","start":"2026-07-07T13:12:17.380822Z","end":"2026-07-07T13:12:17.84273Z","steps":["trace[1835463187] 'agreement among raft nodes before linearized reading' (duration: 461.820418ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:12:17.84278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:12:17.380806Z","time spent":"461.967242ms","remote":"127.0.0.1:58736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-07T13:12:17.843026Z","caller":"traceutil/trace.go:171","msg":"trace[1035762182] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"528.443397ms","start":"2026-07-07T13:12:17.314548Z","end":"2026-07-07T13:12:17.842991Z","steps":["trace[1035762182] 'process raft request' (duration: 527.846311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:12:17.843387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:12:17.314533Z","time spent":"528.568881ms","remote":"127.0.0.1:59068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T13:13:10.547537Z","caller":"traceutil/trace.go:171","msg":"trace[1872015764] transaction","detail":"{read_only:false; response_revision:1576; number_of_response:1; }","duration":"106.766529ms","start":"2026-07-07T13:13:10.440709Z","end":"2026-07-07T13:13:10.547476Z","steps":["trace[1872015764] 'process raft request' (duration: 102.493552ms)"],"step_count":1}