{"level":"warn","ts":"2026-02-05T15:19:32.438116Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-05T15:19:32.438393Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.175: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.175:2380","--initial-cluster=instance=https://199.19.213.175:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.175:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.175: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-02-05T15:19:32.438715Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-05T15:19:32.438825Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.175:2380"]} {"level":"info","ts":"2026-02-05T15:19:32.439028Z","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-02-05T15:19:32.439912Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.175:2379"]} {"level":"info","ts":"2026-02-05T15:19:32.440111Z","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":2,"max-cpu-available":2,"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.175:2380"],"listen-peer-urls":["https://199.19.213.175:2380"],"advertise-client-urls":["https://199.19.213.175:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.175:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.175: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-02-05T15:19:32.448815Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.875166ms"} {"level":"info","ts":"2026-02-05T15:19:32.454625Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"223c7693b78843e1","cluster-id":"b697af90308fdc01"} {"level":"info","ts":"2026-02-05T15:19:32.45481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 switched to configuration voters=()"} {"level":"info","ts":"2026-02-05T15:19:32.454899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 became follower at term 0"} {"level":"info","ts":"2026-02-05T15:19:32.454992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 223c7693b78843e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-05T15:19:32.455089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 became follower at term 1"} {"level":"info","ts":"2026-02-05T15:19:32.455176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 switched to configuration voters=(2466977072703620065)"} {"level":"warn","ts":"2026-02-05T15:19:32.462665Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-05T15:19:32.474966Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-05T15:19:32.477676Z","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-02-05T15:19:32.482636Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"223c7693b78843e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-05T15:19:32.484941Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"223c7693b78843e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-05T15:19:32.485238Z","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-02-05T15:19:32.485352Z","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-02-05T15:19:32.485416Z","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-02-05T15:19:32.485655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 switched to configuration voters=(2466977072703620065)"} {"level":"info","ts":"2026-02-05T15:19:32.485804Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b697af90308fdc01","local-member-id":"223c7693b78843e1","added-peer-id":"223c7693b78843e1","added-peer-peer-urls":["https://199.19.213.175:2380"]} {"level":"info","ts":"2026-02-05T15:19:32.489623Z","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-02-05T15:19:32.490145Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"223c7693b78843e1","initial-advertise-peer-urls":["https://199.19.213.175:2380"],"listen-peer-urls":["https://199.19.213.175:2380"],"advertise-client-urls":["https://199.19.213.175:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.175:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-05T15:19:32.490294Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-05T15:19:32.490451Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.175:2380"} {"level":"info","ts":"2026-02-05T15:19:32.490535Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.175:2380"} {"level":"info","ts":"2026-02-05T15:19:32.855466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 is starting a new election at term 1"} {"level":"info","ts":"2026-02-05T15:19:32.855678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-05T15:19:32.855717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 received MsgPreVoteResp from 223c7693b78843e1 at term 1"} {"level":"info","ts":"2026-02-05T15:19:32.855764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 became candidate at term 2"} {"level":"info","ts":"2026-02-05T15:19:32.855786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 received MsgVoteResp from 223c7693b78843e1 at term 2"} {"level":"info","ts":"2026-02-05T15:19:32.855804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 became leader at term 2"} {"level":"info","ts":"2026-02-05T15:19:32.855837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 223c7693b78843e1 elected leader 223c7693b78843e1 at term 2"} {"level":"info","ts":"2026-02-05T15:19:32.859051Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T15:19:32.861527Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"223c7693b78843e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.175:2379]}","request-path":"/0/members/223c7693b78843e1/attributes","cluster-id":"b697af90308fdc01","publish-timeout":"7s"} {"level":"info","ts":"2026-02-05T15:19:32.861612Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T15:19:32.861631Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T15:19:32.862819Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-05T15:19:32.862848Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-05T15:19:32.864423Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-05T15:19:32.86443Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.175:2379"} {"level":"info","ts":"2026-02-05T15:19:32.890768Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b697af90308fdc01","local-member-id":"223c7693b78843e1","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T15:19:32.890956Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T15:19:32.891033Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-05T15:19:50.741825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.845579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-02-05T15:19:50.742041Z","caller":"traceutil/trace.go:171","msg":"trace[11842110] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:380; }","duration":"458.113054ms","start":"2026-02-05T15:19:50.283913Z","end":"2026-02-05T15:19:50.742026Z","steps":["trace[11842110] 'range keys from in-memory index tree' (duration: 457.762757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:19:50.742084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:19:50.283895Z","time spent":"458.179585ms","remote":"127.0.0.1:33762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4057,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-05T15:19:50.742527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.141374495s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:19:50.742606Z","caller":"traceutil/trace.go:171","msg":"trace[1244172063] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:380; }","duration":"1.141479178s","start":"2026-02-05T15:19:49.601095Z","end":"2026-02-05T15:19:50.742575Z","steps":["trace[1244172063] 'range keys from in-memory index tree' (duration: 1.141302594s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:19:50.742634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:19:49.601076Z","time spent":"1.141552569s","remote":"127.0.0.1:33372","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-02-05T15:19:50.891124Z","caller":"traceutil/trace.go:171","msg":"trace[85483898] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"137.640132ms","start":"2026-02-05T15:19:50.753463Z","end":"2026-02-05T15:19:50.891103Z","steps":["trace[85483898] 'process raft request' (duration: 137.219124ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:19:57.041927Z","caller":"traceutil/trace.go:171","msg":"trace[900979348] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"102.112688ms","start":"2026-02-05T15:19:56.93979Z","end":"2026-02-05T15:19:57.041902Z","steps":["trace[900979348] 'process raft request' (duration: 101.968405ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:20:07.042835Z","caller":"traceutil/trace.go:171","msg":"trace[1442454403] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"114.849039ms","start":"2026-02-05T15:20:06.927963Z","end":"2026-02-05T15:20:07.042813Z","steps":["trace[1442454403] 'process raft request' (duration: 114.717287ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:20:07.743353Z","caller":"traceutil/trace.go:171","msg":"trace[1615545302] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"662.93131ms","start":"2026-02-05T15:20:07.080392Z","end":"2026-02-05T15:20:07.743323Z","steps":["trace[1615545302] 'process raft request' (duration: 572.940394ms)","trace[1615545302] 'compare' (duration: 89.849533ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:20:07.744184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:07.080373Z","time spent":"663.065952ms","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13205,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:20:08.596923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.606739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-05T15:20:08.597061Z","caller":"traceutil/trace.go:171","msg":"trace[1962414133] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:520; }","duration":"1.406827687s","start":"2026-02-05T15:20:07.190223Z","end":"2026-02-05T15:20:08.597051Z","steps":["trace[1962414133] 'read index received' (duration: 463.033062ms)","trace[1962414133] 'applied index is now lower than readState.Index' (duration: 943.794105ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:20:08.597205Z","caller":"traceutil/trace.go:171","msg":"trace[1070148872] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"1.454295109s","start":"2026-02-05T15:20:07.142902Z","end":"2026-02-05T15:20:08.597197Z","steps":["trace[1070148872] 'process raft request' (duration: 1.229303742s)","trace[1070148872] 'compare' (duration: 224.519397ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:20:08.597238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:07.142885Z","time spent":"1.454337011s","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T15:20:08.597333Z","caller":"traceutil/trace.go:171","msg":"trace[528256618] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"1.275583882s","start":"2026-02-05T15:20:07.321742Z","end":"2026-02-05T15:20:08.597326Z","steps":["trace[528256618] 'process raft request' (duration: 1.275257755s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:08.597357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:07.321724Z","time spent":"1.275620193s","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T15:20:08.597412Z","caller":"traceutil/trace.go:171","msg":"trace[1197449790] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"1.074401688s","start":"2026-02-05T15:20:07.523005Z","end":"2026-02-05T15:20:08.597407Z","steps":["trace[1197449790] 'process raft request' (duration: 1.074027981s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:08.59743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:07.522974Z","time spent":"1.074446279s","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T15:20:08.597554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.407325817s","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-02-05T15:20:08.597568Z","caller":"traceutil/trace.go:171","msg":"trace[858540912] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:508; }","duration":"1.407366357s","start":"2026-02-05T15:20:07.190197Z","end":"2026-02-05T15:20:08.597564Z","steps":["trace[858540912] 'agreement among raft nodes before linearized reading' (duration: 1.407277566s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:08.597581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:07.190184Z","time spent":"1.407394329s","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T15:20:08.605695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.415415047s","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-02-05T15:20:08.605746Z","caller":"traceutil/trace.go:171","msg":"trace[2040696955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:510; }","duration":"1.41549029s","start":"2026-02-05T15:20:07.190244Z","end":"2026-02-05T15:20:08.605734Z","steps":["trace[2040696955] 'agreement among raft nodes before linearized reading' (duration: 1.415376147s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:08.605773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:07.190242Z","time spent":"1.41552332s","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-05T15:20:08.605945Z","caller":"traceutil/trace.go:171","msg":"trace[1388816574] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"887.504368ms","start":"2026-02-05T15:20:07.718434Z","end":"2026-02-05T15:20:08.605938Z","steps":["trace[1388816574] 'process raft request' (duration: 886.979367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:08.609165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:07.718409Z","time spent":"887.573599ms","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T15:20:08.609271Z","caller":"traceutil/trace.go:171","msg":"trace[401300166] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"854.130625ms","start":"2026-02-05T15:20:07.755133Z","end":"2026-02-05T15:20:08.609264Z","steps":["trace[401300166] 'process raft request' (duration: 850.437202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:08.609315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:07.755115Z","time spent":"854.172096ms","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13214,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:20:08.609524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.610349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-05T15:20:08.609547Z","caller":"traceutil/trace.go:171","msg":"trace[141476788] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:510; }","duration":"291.684301ms","start":"2026-02-05T15:20:08.317855Z","end":"2026-02-05T15:20:08.609539Z","steps":["trace[141476788] 'agreement among raft nodes before linearized reading' (duration: 291.613819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:08.609627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.289935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-05T15:20:08.609641Z","caller":"traceutil/trace.go:171","msg":"trace[982862278] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:510; }","duration":"407.331716ms","start":"2026-02-05T15:20:08.202304Z","end":"2026-02-05T15:20:08.609636Z","steps":["trace[982862278] 'agreement among raft nodes before linearized reading' (duration: 407.284395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:08.609653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:08.202291Z","time spent":"407.359597ms","remote":"127.0.0.1:33580","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-02-05T15:20:08.609745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.696771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:20:08.609761Z","caller":"traceutil/trace.go:171","msg":"trace[488131994] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:510; }","duration":"491.746101ms","start":"2026-02-05T15:20:08.11801Z","end":"2026-02-05T15:20:08.609757Z","steps":["trace[488131994] 'agreement among raft nodes before linearized reading' (duration: 491.72218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:08.609773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:08.117992Z","time spent":"491.777752ms","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-05T15:20:08.60981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.901385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:20:08.60982Z","caller":"traceutil/trace.go:171","msg":"trace[1181737784] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:510; }","duration":"690.911345ms","start":"2026-02-05T15:20:07.918905Z","end":"2026-02-05T15:20:08.609817Z","steps":["trace[1181737784] 'agreement among raft nodes before linearized reading' (duration: 690.893085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:08.609831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:07.918866Z","time spent":"690.962016ms","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-05T15:20:08.609866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011956168s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:20:08.609876Z","caller":"traceutil/trace.go:171","msg":"trace[623743503] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"1.011990378s","start":"2026-02-05T15:20:07.597882Z","end":"2026-02-05T15:20:08.609873Z","steps":["trace[623743503] 'agreement among raft nodes before linearized reading' (duration: 1.011973998s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:08.609886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:07.597862Z","time spent":"1.012021208s","remote":"127.0.0.1:33372","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-02-05T15:20:08.609964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.273952629s","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-02-05T15:20:08.609982Z","caller":"traceutil/trace.go:171","msg":"trace[2086146080] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:510; }","duration":"1.27399323s","start":"2026-02-05T15:20:07.335983Z","end":"2026-02-05T15:20:08.609976Z","steps":["trace[2086146080] 'agreement among raft nodes before linearized reading' (duration: 1.27396103s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:08.609999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:07.33597Z","time spent":"1.274024821s","remote":"127.0.0.1:33580","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-02-05T15:20:08.610082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.418940696s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T15:20:08.610099Z","caller":"traceutil/trace.go:171","msg":"trace[1776328661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:510; }","duration":"1.418971026s","start":"2026-02-05T15:20:07.191123Z","end":"2026-02-05T15:20:08.610094Z","steps":["trace[1776328661] 'agreement among raft nodes before linearized reading' (duration: 1.418938416s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:08.610117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:07.191117Z","time spent":"1.418993578s","remote":"127.0.0.1:33580","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-02-05T15:20:09.219556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.0179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T15:20:09.219902Z","caller":"traceutil/trace.go:171","msg":"trace[1153565134] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"534.770505ms","start":"2026-02-05T15:20:08.685114Z","end":"2026-02-05T15:20:09.219885Z","steps":["trace[1153565134] 'process raft request' (duration: 534.734035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:09.219978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:08.685095Z","time spent":"534.848648ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T15:20:09.220274Z","caller":"traceutil/trace.go:171","msg":"trace[1949378793] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"614.496048ms","start":"2026-02-05T15:20:08.605764Z","end":"2026-02-05T15:20:09.22026Z","steps":["trace[1949378793] 'process raft request' (duration: 269.698044ms)","trace[1949378793] 'compare' (duration: 343.742633ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:20:09.220362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:08.605744Z","time spent":"614.564919ms","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9296,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T15:20:09.220495Z","caller":"traceutil/trace.go:171","msg":"trace[1593386847] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:526; }","duration":"596.913109ms","start":"2026-02-05T15:20:08.623572Z","end":"2026-02-05T15:20:09.220485Z","steps":["trace[1593386847] 'read index received' (duration: 252.114255ms)","trace[1593386847] 'applied index is now lower than readState.Index' (duration: 344.797584ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:20:09.220645Z","caller":"traceutil/trace.go:171","msg":"trace[1496493729] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"596.98433ms","start":"2026-02-05T15:20:08.623651Z","end":"2026-02-05T15:20:09.220635Z","steps":["trace[1496493729] 'process raft request' (duration: 595.999111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:09.220714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:08.62364Z","time spent":"597.034231ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T15:20:09.220856Z","caller":"traceutil/trace.go:171","msg":"trace[754702101] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"597.081702ms","start":"2026-02-05T15:20:08.623764Z","end":"2026-02-05T15:20:09.220846Z","steps":["trace[754702101] 'process raft request' (duration: 595.96686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:09.220915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:08.623757Z","time spent":"597.129453ms","remote":"127.0.0.1:33580","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-02-05T15:20:09.221071Z","caller":"traceutil/trace.go:171","msg":"trace[491041302] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"595.662663ms","start":"2026-02-05T15:20:08.625395Z","end":"2026-02-05T15:20:09.221057Z","steps":["trace[491041302] 'process raft request' (duration: 594.368768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:09.221153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:08.625381Z","time spent":"595.730345ms","remote":"127.0.0.1:33580","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-02-05T15:20:09.221296Z","caller":"traceutil/trace.go:171","msg":"trace[16250108] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"595.750996ms","start":"2026-02-05T15:20:08.625535Z","end":"2026-02-05T15:20:09.221286Z","steps":["trace[16250108] 'process raft request' (duration: 594.255917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:09.221357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:08.625527Z","time spent":"595.801717ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T15:20:09.221466Z","caller":"traceutil/trace.go:171","msg":"trace[1048050089] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"587.474761ms","start":"2026-02-05T15:20:08.633982Z","end":"2026-02-05T15:20:09.221457Z","steps":["trace[1048050089] 'process raft request' (duration: 585.838829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:09.221515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:08.633966Z","time spent":"587.521212ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:20:09.221715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.139524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:20:09.221746Z","caller":"traceutil/trace.go:171","msg":"trace[128142978] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"598.192164ms","start":"2026-02-05T15:20:08.623543Z","end":"2026-02-05T15:20:09.221735Z","steps":["trace[128142978] 'agreement among raft nodes before linearized reading' (duration: 598.145624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:09.221774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:08.62353Z","time spent":"598.238225ms","remote":"127.0.0.1:33368","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-02-05T15:20:09.221977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.597777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-05T15:20:09.222009Z","caller":"traceutil/trace.go:171","msg":"trace[469325130] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:517; }","duration":"503.652528ms","start":"2026-02-05T15:20:08.718346Z","end":"2026-02-05T15:20:09.221998Z","steps":["trace[469325130] 'agreement among raft nodes before linearized reading' (duration: 503.546805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:09.222037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:08.718296Z","time spent":"503.733329ms","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5200,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"info","ts":"2026-02-05T15:20:09.222488Z","caller":"traceutil/trace.go:171","msg":"trace[1170816555] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:517; }","duration":"100.022315ms","start":"2026-02-05T15:20:09.122456Z","end":"2026-02-05T15:20:09.222478Z","steps":["trace[1170816555] 'agreement among raft nodes before linearized reading' (duration: 99.960414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:09.222875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.223819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-05T15:20:09.22299Z","caller":"traceutil/trace.go:171","msg":"trace[550562724] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:517; }","duration":"304.365522ms","start":"2026-02-05T15:20:08.918613Z","end":"2026-02-05T15:20:09.222979Z","steps":["trace[550562724] 'agreement among raft nodes before linearized reading' (duration: 303.982824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:09.223025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:08.91857Z","time spent":"304.445293ms","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4106,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"info","ts":"2026-02-05T15:20:09.339063Z","caller":"traceutil/trace.go:171","msg":"trace[334594720] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"100.129987ms","start":"2026-02-05T15:20:09.238917Z","end":"2026-02-05T15:20:09.339047Z","steps":["trace[334594720] 'process raft request' (duration: 100.056036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:16.102059Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4891362393346108363,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T15:20:16.210262Z","caller":"traceutil/trace.go:171","msg":"trace[108649436] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"669.964709ms","start":"2026-02-05T15:20:15.540256Z","end":"2026-02-05T15:20:16.210221Z","steps":["trace[108649436] 'process raft request' (duration: 669.779335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:16.210462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:15.540222Z","time spent":"670.131262ms","remote":"127.0.0.1:33514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T15:20:16.224589Z","caller":"traceutil/trace.go:171","msg":"trace[762036365] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:567; }","duration":"622.828124ms","start":"2026-02-05T15:20:15.601703Z","end":"2026-02-05T15:20:16.224531Z","steps":["trace[762036365] 'read index received' (duration: 608.403028ms)","trace[762036365] 'applied index is now lower than readState.Index' (duration: 14.424406ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:20:16.225006Z","caller":"traceutil/trace.go:171","msg":"trace[74075776] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"327.723095ms","start":"2026-02-05T15:20:15.897264Z","end":"2026-02-05T15:20:16.224988Z","steps":["trace[74075776] 'process raft request' (duration: 327.177594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:16.225951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.333245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-05T15:20:16.226267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:15.897244Z","time spent":"327.873838ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T15:20:16.226428Z","caller":"traceutil/trace.go:171","msg":"trace[305053806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:552; }","duration":"624.327273ms","start":"2026-02-05T15:20:15.601677Z","end":"2026-02-05T15:20:16.226004Z","steps":["trace[305053806] 'agreement among raft nodes before linearized reading' (duration: 622.999167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:16.227012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:15.601661Z","time spent":"625.341593ms","remote":"127.0.0.1:33368","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-02-05T15:20:17.101245Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4891362393346108367,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T15:20:17.601778Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4891362393346108367,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T15:20:17.750666Z","caller":"traceutil/trace.go:171","msg":"trace[347966968] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:569; }","duration":"1.150365866s","start":"2026-02-05T15:20:16.600277Z","end":"2026-02-05T15:20:17.750643Z","steps":["trace[347966968] 'read index received' (duration: 1.150165292s)","trace[347966968] 'applied index is now lower than readState.Index' (duration: 199.274µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:20:17.750849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.150540209s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:20:17.750917Z","caller":"traceutil/trace.go:171","msg":"trace[1555645198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:552; }","duration":"1.150621241s","start":"2026-02-05T15:20:16.600273Z","end":"2026-02-05T15:20:17.750894Z","steps":["trace[1555645198] 'agreement among raft nodes before linearized reading' (duration: 1.150486588s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:17.750692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:16.45301Z","time spent":"1.29767444s","remote":"127.0.0.1:33388","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-05T15:20:17.751106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.480747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-05T15:20:17.751142Z","caller":"traceutil/trace.go:171","msg":"trace[1788565243] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:552; }","duration":"482.550918ms","start":"2026-02-05T15:20:17.268582Z","end":"2026-02-05T15:20:17.751133Z","steps":["trace[1788565243] 'agreement among raft nodes before linearized reading' (duration: 482.421676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:17.751176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:17.268562Z","time spent":"482.60725ms","remote":"127.0.0.1:33580","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-02-05T15:20:17.750963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:16.600237Z","time spent":"1.150719503s","remote":"127.0.0.1:33372","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-02-05T15:20:17.751478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.112993ms","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-02-05T15:20:17.751517Z","caller":"traceutil/trace.go:171","msg":"trace[578467894] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:552; }","duration":"460.169645ms","start":"2026-02-05T15:20:17.291338Z","end":"2026-02-05T15:20:17.751508Z","steps":["trace[578467894] 'agreement among raft nodes before linearized reading' (duration: 460.069453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:17.75158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:17.291328Z","time spent":"460.244216ms","remote":"127.0.0.1:33580","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-02-05T15:20:17.751805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.790467ms","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-02-05T15:20:17.751843Z","caller":"traceutil/trace.go:171","msg":"trace[603126416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:552; }","duration":"461.845328ms","start":"2026-02-05T15:20:17.289988Z","end":"2026-02-05T15:20:17.751833Z","steps":["trace[603126416] 'agreement among raft nodes before linearized reading' (duration: 461.710364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:17.751871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:17.289976Z","time spent":"461.888139ms","remote":"127.0.0.1:33580","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-02-05T15:20:17.752042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.719835ms","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-02-05T15:20:17.752077Z","caller":"traceutil/trace.go:171","msg":"trace[1240806711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:552; }","duration":"467.779625ms","start":"2026-02-05T15:20:17.284289Z","end":"2026-02-05T15:20:17.752068Z","steps":["trace[1240806711] 'agreement among raft nodes before linearized reading' (duration: 467.690254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:17.752108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:17.284273Z","time spent":"467.828716ms","remote":"127.0.0.1:33580","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-02-05T15:20:17.752459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.943347ms","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-02-05T15:20:17.753041Z","caller":"traceutil/trace.go:171","msg":"trace[780146248] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:552; }","duration":"476.55917ms","start":"2026-02-05T15:20:17.276465Z","end":"2026-02-05T15:20:17.753024Z","steps":["trace[780146248] 'agreement among raft nodes before linearized reading' (duration: 475.859636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:17.754679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:17.276454Z","time spent":"478.203693ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T15:20:18.279045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.103428ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T15:20:18.279135Z","caller":"traceutil/trace.go:171","msg":"trace[1813212433] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"523.743217ms","start":"2026-02-05T15:20:17.755375Z","end":"2026-02-05T15:20:18.279119Z","steps":["trace[1813212433] 'process raft request' (duration: 363.498926ms)","trace[1813212433] 'compare' (duration: 159.991936ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:20:18.279191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:17.755357Z","time spent":"523.807059ms","remote":"127.0.0.1:33388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T15:20:18.563087Z","caller":"traceutil/trace.go:171","msg":"trace[1143038934] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:570; }","duration":"799.441739ms","start":"2026-02-05T15:20:17.76363Z","end":"2026-02-05T15:20:18.563071Z","steps":["trace[1143038934] 'read index received' (duration: 355.201242ms)","trace[1143038934] 'applied index is now lower than readState.Index' (duration: 444.239857ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:20:18.563374Z","caller":"traceutil/trace.go:171","msg":"trace[1930849779] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"804.358797ms","start":"2026-02-05T15:20:17.759007Z","end":"2026-02-05T15:20:18.563366Z","steps":["trace[1930849779] 'process raft request' (duration: 799.060392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:18.563448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:17.758995Z","time spent":"804.400857ms","remote":"127.0.0.1:33580","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-02-05T15:20:18.563572Z","caller":"traceutil/trace.go:171","msg":"trace[2078505933] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"800.268525ms","start":"2026-02-05T15:20:17.763296Z","end":"2026-02-05T15:20:18.563564Z","steps":["trace[2078505933] 'process raft request' (duration: 799.689033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:18.563605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:17.763289Z","time spent":"800.297576ms","remote":"127.0.0.1:33580","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-02-05T15:20:18.563687Z","caller":"traceutil/trace.go:171","msg":"trace[1348237471] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"799.367318ms","start":"2026-02-05T15:20:17.764314Z","end":"2026-02-05T15:20:18.563682Z","steps":["trace[1348237471] 'process raft request' (duration: 798.696635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:18.563718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:17.764309Z","time spent":"799.392218ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T15:20:18.564846Z","caller":"traceutil/trace.go:171","msg":"trace[1886840727] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"798.672914ms","start":"2026-02-05T15:20:17.766128Z","end":"2026-02-05T15:20:18.564801Z","steps":["trace[1886840727] 'process raft request' (duration: 796.902098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:18.564991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:17.766121Z","time spent":"798.791776ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T15:20:18.565115Z","caller":"traceutil/trace.go:171","msg":"trace[1221364914] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"798.341898ms","start":"2026-02-05T15:20:17.766764Z","end":"2026-02-05T15:20:18.565106Z","steps":["trace[1221364914] 'process raft request' (duration: 796.283806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:18.565217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:17.766758Z","time spent":"798.375818ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:20:18.565244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.609892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:20:18.565274Z","caller":"traceutil/trace.go:171","msg":"trace[363688505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"801.650893ms","start":"2026-02-05T15:20:17.763615Z","end":"2026-02-05T15:20:18.565265Z","steps":["trace[363688505] 'agreement among raft nodes before linearized reading' (duration: 801.604842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:18.565294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:17.763582Z","time spent":"801.708404ms","remote":"127.0.0.1:33372","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-02-05T15:20:18.565411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.219053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-05T15:20:18.565442Z","caller":"traceutil/trace.go:171","msg":"trace[913510506] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:558; }","duration":"284.272503ms","start":"2026-02-05T15:20:18.28116Z","end":"2026-02-05T15:20:18.565432Z","steps":["trace[913510506] 'agreement among raft nodes before linearized reading' (duration: 284.199902ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:20:25.903336Z","caller":"traceutil/trace.go:171","msg":"trace[2055508633] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"267.388407ms","start":"2026-02-05T15:20:25.63592Z","end":"2026-02-05T15:20:25.903309Z","steps":["trace[2055508633] 'process raft request' (duration: 267.209694ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:20:25.905375Z","caller":"traceutil/trace.go:171","msg":"trace[1111854400] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"179.164086ms","start":"2026-02-05T15:20:25.726192Z","end":"2026-02-05T15:20:25.905356Z","steps":["trace[1111854400] 'process raft request' (duration: 178.946322ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:20:25.905889Z","caller":"traceutil/trace.go:171","msg":"trace[1666691550] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"265.549402ms","start":"2026-02-05T15:20:25.640328Z","end":"2026-02-05T15:20:25.905878Z","steps":["trace[1666691550] 'process raft request' (duration: 264.668764ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:20:28.863248Z","caller":"traceutil/trace.go:171","msg":"trace[1840312329] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"117.959901ms","start":"2026-02-05T15:20:28.745262Z","end":"2026-02-05T15:20:28.863222Z","steps":["trace[1840312329] 'process raft request' (duration: 116.501461ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:20:28.868247Z","caller":"traceutil/trace.go:171","msg":"trace[1065358179] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"109.094535ms","start":"2026-02-05T15:20:28.759123Z","end":"2026-02-05T15:20:28.868217Z","steps":["trace[1065358179] 'process raft request' (duration: 108.799639ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:20:28.868673Z","caller":"traceutil/trace.go:171","msg":"trace[1858139758] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"109.478433ms","start":"2026-02-05T15:20:28.759184Z","end":"2026-02-05T15:20:28.868663Z","steps":["trace[1858139758] 'process raft request' (duration: 108.869491ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:20:28.868869Z","caller":"traceutil/trace.go:171","msg":"trace[1870885457] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"108.501464ms","start":"2026-02-05T15:20:28.760359Z","end":"2026-02-05T15:20:28.86886Z","steps":["trace[1870885457] 'process raft request' (duration: 108.255519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:40.743295Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4891362393346108657,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T15:20:40.755092Z","caller":"traceutil/trace.go:171","msg":"trace[839585681] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"623.535478ms","start":"2026-02-05T15:20:40.13154Z","end":"2026-02-05T15:20:40.755075Z","steps":["trace[839585681] 'process raft request' (duration: 623.454066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:40.7552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:40.131514Z","time spent":"623.63371ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:20:42.213255Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.372494164s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T15:20:42.213667Z","caller":"traceutil/trace.go:171","msg":"trace[1449349266] linearizableReadLoop","detail":"{readStateIndex:729; appliedIndex:727; }","duration":"1.970446155s","start":"2026-02-05T15:20:40.243203Z","end":"2026-02-05T15:20:42.213649Z","steps":["trace[1449349266] 'read index received' (duration: 512.322891ms)","trace[1449349266] 'applied index is now lower than readState.Index' (duration: 1.458122164s)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:20:42.213884Z","caller":"traceutil/trace.go:171","msg":"trace[1282784110] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"1.963081899s","start":"2026-02-05T15:20:40.250795Z","end":"2026-02-05T15:20:42.213877Z","steps":["trace[1282784110] 'process raft request' (duration: 1.962668561s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:42.213954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:40.250782Z","time spent":"1.963120079s","remote":"127.0.0.1:33510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4769,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:20:42.214082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:40.598786Z","time spent":"1.615294895s","remote":"127.0.0.1:33436","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-05T15:20:42.21419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.970981176s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:20:42.214216Z","caller":"traceutil/trace.go:171","msg":"trace[1182953032] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:705; }","duration":"1.971033186s","start":"2026-02-05T15:20:40.243174Z","end":"2026-02-05T15:20:42.214207Z","steps":["trace[1182953032] 'agreement among raft nodes before linearized reading' (duration: 1.970990566s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:42.214235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:40.243161Z","time spent":"1.971068637s","remote":"127.0.0.1:33490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-02-05T15:20:42.54187Z","caller":"traceutil/trace.go:171","msg":"trace[2005439841] linearizableReadLoop","detail":"{readStateIndex:730; appliedIndex:729; }","duration":"328.152954ms","start":"2026-02-05T15:20:42.213694Z","end":"2026-02-05T15:20:42.541847Z","steps":["trace[2005439841] 'read index received' (duration: 273.118151ms)","trace[2005439841] 'applied index is now lower than readState.Index' (duration: 55.033563ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:20:42.541963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.944129262s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:20:42.541994Z","caller":"traceutil/trace.go:171","msg":"trace[1700940120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:705; }","duration":"1.944201764s","start":"2026-02-05T15:20:40.59778Z","end":"2026-02-05T15:20:42.541982Z","steps":["trace[1700940120] 'agreement among raft nodes before linearized reading' (duration: 1.944141523s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:42.542024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:40.597768Z","time spent":"1.944246894s","remote":"127.0.0.1:33368","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-02-05T15:20:42.542141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.541874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T15:20:42.542164Z","caller":"traceutil/trace.go:171","msg":"trace[160545871] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:705; }","duration":"520.569074ms","start":"2026-02-05T15:20:42.021588Z","end":"2026-02-05T15:20:42.542157Z","steps":["trace[160545871] 'agreement among raft nodes before linearized reading' (duration: 520.525273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:42.54218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:42.021538Z","time spent":"520.637826ms","remote":"127.0.0.1:33790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-02-05T15:20:42.542189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.599906789s","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-02-05T15:20:42.54221Z","caller":"traceutil/trace.go:171","msg":"trace[1849255815] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:705; }","duration":"1.599951169s","start":"2026-02-05T15:20:40.942252Z","end":"2026-02-05T15:20:42.542203Z","steps":["trace[1849255815] 'agreement among raft nodes before linearized reading' (duration: 1.599895048s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:42.542225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:40.942234Z","time spent":"1.59998697s","remote":"127.0.0.1:33580","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-02-05T15:20:42.542263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.062979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:20:42.542281Z","caller":"traceutil/trace.go:171","msg":"trace[1888519799] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:705; }","duration":"816.16324ms","start":"2026-02-05T15:20:41.726113Z","end":"2026-02-05T15:20:42.542276Z","steps":["trace[1888519799] 'agreement among raft nodes before linearized reading' (duration: 816.13758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:42.542295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:41.726095Z","time spent":"816.195951ms","remote":"127.0.0.1:33544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-02-05T15:20:42.542327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.609111341s","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-02-05T15:20:42.542342Z","caller":"traceutil/trace.go:171","msg":"trace[1393182359] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:705; }","duration":"1.609150452s","start":"2026-02-05T15:20:40.933186Z","end":"2026-02-05T15:20:42.542337Z","steps":["trace[1393182359] 'agreement among raft nodes before linearized reading' (duration: 1.609109431s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:42.542354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:40.933172Z","time spent":"1.609179662s","remote":"127.0.0.1:33580","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":"warn","ts":"2026-02-05T15:20:42.542399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.594038872s","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-02-05T15:20:42.542413Z","caller":"traceutil/trace.go:171","msg":"trace[1670449489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:705; }","duration":"1.594065903s","start":"2026-02-05T15:20:40.948342Z","end":"2026-02-05T15:20:42.542408Z","steps":["trace[1670449489] 'agreement among raft nodes before linearized reading' (duration: 1.594025112s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:42.542426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:40.948338Z","time spent":"1.594084573s","remote":"127.0.0.1:33580","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-02-05T15:20:42.542453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.721715067s","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-02-05T15:20:42.542466Z","caller":"traceutil/trace.go:171","msg":"trace[376589870] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:705; }","duration":"1.721764198s","start":"2026-02-05T15:20:40.820698Z","end":"2026-02-05T15:20:42.542462Z","steps":["trace[376589870] 'agreement among raft nodes before linearized reading' (duration: 1.721710717s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:42.542479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:40.820682Z","time spent":"1.721794458s","remote":"127.0.0.1:33580","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-02-05T15:20:42.542559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.734520301s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T15:20:42.542572Z","caller":"traceutil/trace.go:171","msg":"trace[426660306] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:705; }","duration":"1.734562341s","start":"2026-02-05T15:20:40.808006Z","end":"2026-02-05T15:20:42.542569Z","steps":["trace[426660306] 'agreement among raft nodes before linearized reading' (duration: 1.734538931s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:42.542609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:40.807972Z","time spent":"1.734632923s","remote":"127.0.0.1:33762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":2,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-05T15:20:42.542677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.755668361s","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-02-05T15:20:42.542698Z","caller":"traceutil/trace.go:171","msg":"trace[731711165] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:705; }","duration":"1.755725333s","start":"2026-02-05T15:20:40.786966Z","end":"2026-02-05T15:20:42.542691Z","steps":["trace[731711165] 'agreement among raft nodes before linearized reading' (duration: 1.755694432s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:42.542714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:40.786924Z","time spent":"1.755785413s","remote":"127.0.0.1:33726","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-02-05T15:20:42.541964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.593619224s","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-02-05T15:20:42.542797Z","caller":"traceutil/trace.go:171","msg":"trace[1842702960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:705; }","duration":"1.594480951s","start":"2026-02-05T15:20:40.948312Z","end":"2026-02-05T15:20:42.542793Z","steps":["trace[1842702960] 'agreement among raft nodes before linearized reading' (duration: 1.593551082s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:42.542813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:40.948301Z","time spent":"1.594508132s","remote":"127.0.0.1:33580","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-02-05T15:20:42.557443Z","caller":"traceutil/trace.go:171","msg":"trace[49733300] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"339.119442ms","start":"2026-02-05T15:20:42.218308Z","end":"2026-02-05T15:20:42.557428Z","steps":["trace[49733300] 'process raft request' (duration: 339.02355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:20:42.557603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:20:42.218292Z","time spent":"339.266694ms","remote":"127.0.0.1:33436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T15:20:42.755857Z","caller":"traceutil/trace.go:171","msg":"trace[1154045643] linearizableReadLoop","detail":"{readStateIndex:736; appliedIndex:731; }","duration":"201.739475ms","start":"2026-02-05T15:20:42.554103Z","end":"2026-02-05T15:20:42.755842Z","steps":["trace[1154045643] 'read index received' (duration: 3.936549ms)","trace[1154045643] 'applied index is now lower than readState.Index' (duration: 197.802186ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:20:42.755949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.833556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:20:42.755979Z","caller":"traceutil/trace.go:171","msg":"trace[444770392] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:711; }","duration":"201.888308ms","start":"2026-02-05T15:20:42.554084Z","end":"2026-02-05T15:20:42.755972Z","steps":["trace[444770392] 'agreement among raft nodes before linearized reading' (duration: 201.817747ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:20:42.756174Z","caller":"traceutil/trace.go:171","msg":"trace[1110044010] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"207.601361ms","start":"2026-02-05T15:20:42.548565Z","end":"2026-02-05T15:20:42.756166Z","steps":["trace[1110044010] 'process raft request' (duration: 197.893449ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:20:42.756264Z","caller":"traceutil/trace.go:171","msg":"trace[929714524] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"207.619202ms","start":"2026-02-05T15:20:42.548637Z","end":"2026-02-05T15:20:42.756256Z","steps":["trace[929714524] 'process raft request' (duration: 207.115332ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:20:42.756336Z","caller":"traceutil/trace.go:171","msg":"trace[983253774] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"207.633121ms","start":"2026-02-05T15:20:42.548696Z","end":"2026-02-05T15:20:42.756329Z","steps":["trace[983253774] 'process raft request' (duration: 207.0829ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:20:42.756403Z","caller":"traceutil/trace.go:171","msg":"trace[1023228757] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"207.676883ms","start":"2026-02-05T15:20:42.548721Z","end":"2026-02-05T15:20:42.756398Z","steps":["trace[1023228757] 'process raft request' (duration: 207.077071ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:20:42.756471Z","caller":"traceutil/trace.go:171","msg":"trace[1567606924] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"203.251884ms","start":"2026-02-05T15:20:42.553214Z","end":"2026-02-05T15:20:42.756466Z","steps":["trace[1567606924] 'process raft request' (duration: 202.606651ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:21:12.069866Z","caller":"traceutil/trace.go:171","msg":"trace[305401525] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"347.540076ms","start":"2026-02-05T15:21:11.722311Z","end":"2026-02-05T15:21:12.069851Z","steps":["trace[305401525] 'process raft request' (duration: 347.435154ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:12.069961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:11.72229Z","time spent":"347.622308ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:21:13.327405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.544366ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T15:21:13.32775Z","caller":"traceutil/trace.go:171","msg":"trace[2109797538] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"296.218298ms","start":"2026-02-05T15:21:13.031514Z","end":"2026-02-05T15:21:13.327732Z","steps":["trace[2109797538] 'process raft request' (duration: 296.107046ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:21:13.328193Z","caller":"traceutil/trace.go:171","msg":"trace[14351449] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"301.336524ms","start":"2026-02-05T15:21:13.026839Z","end":"2026-02-05T15:21:13.328176Z","steps":["trace[14351449] 'process raft request' (duration: 95.908979ms)","trace[14351449] 'compare' (duration: 204.400173ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:21:13.328261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:13.026824Z","time spent":"301.398585ms","remote":"127.0.0.1:33580","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-02-05T15:21:13.328394Z","caller":"traceutil/trace.go:171","msg":"trace[271851494] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"299.370423ms","start":"2026-02-05T15:21:13.029014Z","end":"2026-02-05T15:21:13.328384Z","steps":["trace[271851494] 'process raft request' (duration: 298.506575ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:21:13.328503Z","caller":"traceutil/trace.go:171","msg":"trace[348379859] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"297.054865ms","start":"2026-02-05T15:21:13.031441Z","end":"2026-02-05T15:21:13.328496Z","steps":["trace[348379859] 'process raft request' (duration: 296.142796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:13.855834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.828005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:21:13.855886Z","caller":"traceutil/trace.go:171","msg":"trace[912021035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:851; }","duration":"258.909737ms","start":"2026-02-05T15:21:13.596963Z","end":"2026-02-05T15:21:13.855873Z","steps":["trace[912021035] 'range keys from in-memory index tree' (duration: 258.773134ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:21:17.186831Z","caller":"traceutil/trace.go:171","msg":"trace[225309524] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"147.86161ms","start":"2026-02-05T15:21:17.038953Z","end":"2026-02-05T15:21:17.186814Z","steps":["trace[225309524] 'process raft request' (duration: 147.732508ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:21:31.792979Z","caller":"traceutil/trace.go:171","msg":"trace[1122242103] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"357.98592ms","start":"2026-02-05T15:21:31.434977Z","end":"2026-02-05T15:21:31.792963Z","steps":["trace[1122242103] 'process raft request' (duration: 357.819496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:31.793246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:31.434959Z","time spent":"358.234985ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T15:21:31.796401Z","caller":"traceutil/trace.go:171","msg":"trace[1952306245] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"360.642774ms","start":"2026-02-05T15:21:31.435742Z","end":"2026-02-05T15:21:31.796385Z","steps":["trace[1952306245] 'process raft request' (duration: 360.494241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:31.796483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:31.435733Z","time spent":"360.704135ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T15:21:31.812092Z","caller":"traceutil/trace.go:171","msg":"trace[1665695305] linearizableReadLoop","detail":"{readStateIndex:936; appliedIndex:934; }","duration":"372.944315ms","start":"2026-02-05T15:21:31.43913Z","end":"2026-02-05T15:21:31.812074Z","steps":["trace[1665695305] 'read index received' (duration: 353.732463ms)","trace[1665695305] 'applied index is now lower than readState.Index' (duration: 19.210692ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:21:31.812342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.19491ms","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-02-05T15:21:31.812369Z","caller":"traceutil/trace.go:171","msg":"trace[293901724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:900; }","duration":"373.247011ms","start":"2026-02-05T15:21:31.439114Z","end":"2026-02-05T15:21:31.812361Z","steps":["trace[293901724] 'agreement among raft nodes before linearized reading' (duration: 373.136119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:31.812391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:31.439108Z","time spent":"373.276232ms","remote":"127.0.0.1:33580","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-02-05T15:21:31.812717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.580861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:21:31.81274Z","caller":"traceutil/trace.go:171","msg":"trace[667909709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:900; }","duration":"212.630882ms","start":"2026-02-05T15:21:31.600102Z","end":"2026-02-05T15:21:31.812732Z","steps":["trace[667909709] 'agreement among raft nodes before linearized reading' (duration: 212.573051ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:21:36.181186Z","caller":"traceutil/trace.go:171","msg":"trace[345526643] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:954; }","duration":"363.652145ms","start":"2026-02-05T15:21:35.817516Z","end":"2026-02-05T15:21:36.181168Z","steps":["trace[345526643] 'read index received' (duration: 363.526653ms)","trace[345526643] 'applied index is now lower than readState.Index' (duration: 124.722µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:21:36.181414Z","caller":"traceutil/trace.go:171","msg":"trace[1137485636] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"364.549734ms","start":"2026-02-05T15:21:35.816857Z","end":"2026-02-05T15:21:36.181407Z","steps":["trace[1137485636] 'process raft request' (duration: 364.230207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:36.181484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:35.816842Z","time spent":"364.587964ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:21:36.181625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.106305ms","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-02-05T15:21:36.181642Z","caller":"traceutil/trace.go:171","msg":"trace[1933753681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:918; }","duration":"364.138185ms","start":"2026-02-05T15:21:35.817499Z","end":"2026-02-05T15:21:36.181637Z","steps":["trace[1933753681] 'agreement among raft nodes before linearized reading' (duration: 364.070863ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:36.181655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:35.817493Z","time spent":"364.159756ms","remote":"127.0.0.1:33580","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-02-05T15:21:36.181735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.443241ms","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-02-05T15:21:36.181747Z","caller":"traceutil/trace.go:171","msg":"trace[1802362035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:918; }","duration":"338.482322ms","start":"2026-02-05T15:21:35.843261Z","end":"2026-02-05T15:21:36.181744Z","steps":["trace[1802362035] 'agreement among raft nodes before linearized reading' (duration: 338.445531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:36.181758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:35.843246Z","time spent":"338.509433ms","remote":"127.0.0.1:33580","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-02-05T15:21:37.029835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.648793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.175\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-05T15:21:37.029896Z","caller":"traceutil/trace.go:171","msg":"trace[334811409] range","detail":"{range_begin:/registry/masterleases/199.19.213.175; range_end:; response_count:1; response_revision:920; }","duration":"572.773225ms","start":"2026-02-05T15:21:36.45711Z","end":"2026-02-05T15:21:37.029883Z","steps":["trace[334811409] 'range keys from in-memory index tree' (duration: 572.50386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:37.029931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:36.457088Z","time spent":"572.830776ms","remote":"127.0.0.1:33388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.175\" "} {"level":"warn","ts":"2026-02-05T15:21:37.03007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.68345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:21:37.030124Z","caller":"traceutil/trace.go:171","msg":"trace[1407741436] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:920; }","duration":"423.778733ms","start":"2026-02-05T15:21:36.606331Z","end":"2026-02-05T15:21:37.030109Z","steps":["trace[1407741436] 'range keys from in-memory index tree' (duration: 423.598119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:37.03015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:21:36.606313Z","time spent":"423.831984ms","remote":"127.0.0.1:33372","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-02-05T15:21:47.713493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.42533ms","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-02-05T15:21:47.713698Z","caller":"traceutil/trace.go:171","msg":"trace[750467338] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:985; }","duration":"240.746106ms","start":"2026-02-05T15:21:47.472936Z","end":"2026-02-05T15:21:47.713682Z","steps":["trace[750467338] 'range keys from in-memory index tree' (duration: 240.148594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:47.714136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.48035ms","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-02-05T15:21:47.714267Z","caller":"traceutil/trace.go:171","msg":"trace[1234126620] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:985; }","duration":"215.641753ms","start":"2026-02-05T15:21:47.498615Z","end":"2026-02-05T15:21:47.714256Z","steps":["trace[1234126620] 'range keys from in-memory index tree' (duration: 215.395199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:47.717303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.666413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:21:47.717448Z","caller":"traceutil/trace.go:171","msg":"trace[1961637453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:985; }","duration":"119.835466ms","start":"2026-02-05T15:21:47.5976Z","end":"2026-02-05T15:21:47.717436Z","steps":["trace[1961637453] 'range keys from in-memory index tree' (duration: 119.616692ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:21:47.842648Z","caller":"traceutil/trace.go:171","msg":"trace[185049545] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"124.080264ms","start":"2026-02-05T15:21:47.718547Z","end":"2026-02-05T15:21:47.842627Z","steps":["trace[185049545] 'process raft request' (duration: 123.806518ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:21:48.016922Z","caller":"traceutil/trace.go:171","msg":"trace[901926284] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1027; }","duration":"297.285491ms","start":"2026-02-05T15:21:47.719605Z","end":"2026-02-05T15:21:48.01689Z","steps":["trace[901926284] 'read index received' (duration: 123.694716ms)","trace[901926284] 'applied index is now lower than readState.Index' (duration: 173.589905ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:21:48.017111Z","caller":"traceutil/trace.go:171","msg":"trace[804268454] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"297.564666ms","start":"2026-02-05T15:21:47.719534Z","end":"2026-02-05T15:21:48.017098Z","steps":["trace[804268454] 'process raft request' (duration: 226.784031ms)","trace[804268454] 'compare' (duration: 70.452158ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:21:48.017265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.795654ms","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:510"} {"level":"info","ts":"2026-02-05T15:21:48.01732Z","caller":"traceutil/trace.go:171","msg":"trace[1924121522] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:987; }","duration":"163.913506ms","start":"2026-02-05T15:21:47.85339Z","end":"2026-02-05T15:21:48.017303Z","steps":["trace[1924121522] 'agreement among raft nodes before linearized reading' (duration: 163.754163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:21:48.017415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.808712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:21:48.01743Z","caller":"traceutil/trace.go:171","msg":"trace[599861166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:987; }","duration":"297.822882ms","start":"2026-02-05T15:21:47.719602Z","end":"2026-02-05T15:21:48.017424Z","steps":["trace[599861166] 'agreement among raft nodes before linearized reading' (duration: 297.798012ms)"],"step_count":1}