{"level":"warn","ts":"2026-08-24T15:40:15.490141Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T15:40:15.490275Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.105: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.105:2380","--initial-cluster=instance=https://199.19.213.105:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.105:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.105:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-24T15:40:15.490377Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T15:40:15.490393Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.105:2380"]} {"level":"info","ts":"2026-08-24T15:40:15.49042Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-24T15:40:15.491272Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.105:2379"]} {"level":"info","ts":"2026-08-24T15:40:15.491456Z","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":16,"max-cpu-available":16,"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.105:2380"],"listen-peer-urls":["https://199.19.213.105:2380"],"advertise-client-urls":["https://199.19.213.105:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.105:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.105:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-24T15:40:15.500505Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.738764ms"} {"level":"info","ts":"2026-08-24T15:40:15.508727Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"eb6db91b2c71522f","cluster-id":"f43476f3367c14fb"} {"level":"info","ts":"2026-08-24T15:40:15.508863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f switched to configuration voters=()"} {"level":"info","ts":"2026-08-24T15:40:15.508901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became follower at term 0"} {"level":"info","ts":"2026-08-24T15:40:15.508917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft eb6db91b2c71522f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-24T15:40:15.508928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became follower at term 1"} {"level":"info","ts":"2026-08-24T15:40:15.508976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f switched to configuration voters=(16964418897735406127)"} {"level":"warn","ts":"2026-08-24T15:40:15.51556Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-24T15:40:15.520472Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-24T15:40:15.522059Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-24T15:40:15.525132Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"eb6db91b2c71522f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-24T15:40:15.525365Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"eb6db91b2c71522f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-24T15:40:15.525491Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-24T15:40:15.525648Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-24T15:40:15.52567Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-24T15:40:15.526946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f switched to configuration voters=(16964418897735406127)"} {"level":"info","ts":"2026-08-24T15:40:15.528024Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f43476f3367c14fb","local-member-id":"eb6db91b2c71522f","added-peer-id":"eb6db91b2c71522f","added-peer-peer-urls":["https://199.19.213.105:2380"]} {"level":"info","ts":"2026-08-24T15:40:15.531541Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-24T15:40:15.531729Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.105:2380"} {"level":"info","ts":"2026-08-24T15:40:15.531788Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.105:2380"} {"level":"info","ts":"2026-08-24T15:40:15.531988Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"eb6db91b2c71522f","initial-advertise-peer-urls":["https://199.19.213.105:2380"],"listen-peer-urls":["https://199.19.213.105:2380"],"advertise-client-urls":["https://199.19.213.105:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.105:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-24T15:40:15.532026Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-24T15:40:16.109355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f is starting a new election at term 1"} {"level":"info","ts":"2026-08-24T15:40:16.109429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became pre-candidate at term 1"} {"level":"info","ts":"2026-08-24T15:40:16.109488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f received MsgPreVoteResp from eb6db91b2c71522f at term 1"} {"level":"info","ts":"2026-08-24T15:40:16.109508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became candidate at term 2"} {"level":"info","ts":"2026-08-24T15:40:16.109519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f received MsgVoteResp from eb6db91b2c71522f at term 2"} {"level":"info","ts":"2026-08-24T15:40:16.109535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became leader at term 2"} {"level":"info","ts":"2026-08-24T15:40:16.109548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: eb6db91b2c71522f elected leader eb6db91b2c71522f at term 2"} {"level":"info","ts":"2026-08-24T15:40:16.111105Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"eb6db91b2c71522f","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.105:2379]}","request-path":"/0/members/eb6db91b2c71522f/attributes","cluster-id":"f43476f3367c14fb","publish-timeout":"7s"} {"level":"info","ts":"2026-08-24T15:40:16.111199Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:40:16.111437Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T15:40:16.111939Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T15:40:16.112205Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-24T15:40:16.112268Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-24T15:40:16.112573Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f43476f3367c14fb","local-member-id":"eb6db91b2c71522f","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:40:16.112744Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:40:16.112789Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:40:16.115048Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-24T15:40:16.115996Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.105:2379"} {"level":"warn","ts":"2026-08-24T15:41:13.143984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.401781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-24T15:41:13.144147Z","caller":"traceutil/trace.go:171","msg":"trace[2123651927] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:684; }","duration":"345.698017ms","start":"2026-08-24T15:41:12.798423Z","end":"2026-08-24T15:41:13.144121Z","steps":["trace[2123651927] 'range keys from in-memory index tree' (duration: 345.168337ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:41:13.144208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:41:12.798394Z","time spent":"345.80153ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-24T15:41:14.586221Z","caller":"traceutil/trace.go:171","msg":"trace[625586261] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"321.778408ms","start":"2026-08-24T15:41:14.264422Z","end":"2026-08-24T15:41:14.5862Z","steps":["trace[625586261] 'process raft request' (duration: 321.659586ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:41:14.586802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:41:14.264398Z","time spent":"321.89297ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:41:14.587117Z","caller":"traceutil/trace.go:171","msg":"trace[782840689] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:716; }","duration":"119.044965ms","start":"2026-08-24T15:41:14.468039Z","end":"2026-08-24T15:41:14.587083Z","steps":["trace[782840689] 'read index received' (duration: 118.062807ms)","trace[782840689] 'applied index is now lower than readState.Index' (duration: 980.598µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:41:14.587209Z","caller":"traceutil/trace.go:171","msg":"trace[1064368791] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"322.246056ms","start":"2026-08-24T15:41:14.264954Z","end":"2026-08-24T15:41:14.5872Z","steps":["trace[1064368791] 'process raft request' (duration: 322.055803ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:41:14.587797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:41:14.264921Z","time spent":"322.319448ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:41:14.587906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.653826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-24T15:41:14.587907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.839979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-24T15:41:14.588027Z","caller":"traceutil/trace.go:171","msg":"trace[2133874721] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:689; }","duration":"119.971602ms","start":"2026-08-24T15:41:14.468034Z","end":"2026-08-24T15:41:14.588005Z","steps":["trace[2133874721] 'agreement among raft nodes before linearized reading' (duration: 119.750498ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:41:14.588022Z","caller":"traceutil/trace.go:171","msg":"trace[913165341] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:689; }","duration":"101.798079ms","start":"2026-08-24T15:41:14.486201Z","end":"2026-08-24T15:41:14.587999Z","steps":["trace[913165341] 'agreement among raft nodes before linearized reading' (duration: 101.550505ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:41:31.026407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.11654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-24T15:41:31.026538Z","caller":"traceutil/trace.go:171","msg":"trace[1830530064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:798; }","duration":"361.332113ms","start":"2026-08-24T15:41:30.665175Z","end":"2026-08-24T15:41:31.026507Z","steps":["trace[1830530064] 'range keys from in-memory index tree' (duration: 360.869725ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:41:31.026588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:41:30.665135Z","time spent":"361.441815ms","remote":"127.0.0.1:43572","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-08-24T15:41:31.026902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.109133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:41:31.026905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.824228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-08-24T15:41:31.02695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.78737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-24T15:41:31.027018Z","caller":"traceutil/trace.go:171","msg":"trace[1578918739] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:798; }","duration":"351.988112ms","start":"2026-08-24T15:41:30.675007Z","end":"2026-08-24T15:41:31.026995Z","steps":["trace[1578918739] 'range keys from in-memory index tree' (duration: 351.570384ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:41:31.027023Z","caller":"traceutil/trace.go:171","msg":"trace[1819575388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:798; }","duration":"361.921743ms","start":"2026-08-24T15:41:30.665083Z","end":"2026-08-24T15:41:31.027004Z","steps":["trace[1819575388] 'range keys from in-memory index tree' (duration: 361.571847ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:41:31.027015Z","caller":"traceutil/trace.go:171","msg":"trace[685919297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"113.267485ms","start":"2026-08-24T15:41:30.913718Z","end":"2026-08-24T15:41:31.026986Z","steps":["trace[685919297] 'range keys from in-memory index tree' (duration: 113.046562ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:41:31.027062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:41:30.674982Z","time spent":"352.070302ms","remote":"127.0.0.1:43572","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-08-24T15:41:31.027034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.590304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-24T15:41:31.027076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:41:30.665063Z","time spent":"361.995294ms","remote":"127.0.0.1:43572","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-08-24T15:41:31.027122Z","caller":"traceutil/trace.go:171","msg":"trace[830231462] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:798; }","duration":"362.714216ms","start":"2026-08-24T15:41:30.664387Z","end":"2026-08-24T15:41:31.027101Z","steps":["trace[830231462] 'range keys from in-memory index tree' (duration: 362.428562ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:41:31.027176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:41:30.664365Z","time spent":"362.796987ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-24T15:42:27.114212Z","caller":"traceutil/trace.go:171","msg":"trace[835652149] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1098; }","duration":"134.530982ms","start":"2026-08-24T15:42:26.979644Z","end":"2026-08-24T15:42:27.114175Z","steps":["trace[835652149] 'process raft request' (duration: 134.36392ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:27.122095Z","caller":"traceutil/trace.go:171","msg":"trace[1826778183] linearizableReadLoop","detail":"{readStateIndex:1146; appliedIndex:1144; }","duration":"105.080476ms","start":"2026-08-24T15:42:27.016999Z","end":"2026-08-24T15:42:27.122079Z","steps":["trace[1826778183] 'read index received' (duration: 96.980417ms)","trace[1826778183] 'applied index is now lower than readState.Index' (duration: 8.099289ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:42:27.122144Z","caller":"traceutil/trace.go:171","msg":"trace[1893644022] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1098; }","duration":"142.071045ms","start":"2026-08-24T15:42:26.980041Z","end":"2026-08-24T15:42:27.122112Z","steps":["trace[1893644022] 'process raft request' (duration: 141.970073ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:42:27.122214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.193818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/neutron-coredns\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:42:27.12228Z","caller":"traceutil/trace.go:171","msg":"trace[305421313] range","detail":"{range_begin:/registry/configmaps/openstack/neutron-coredns; range_end:; response_count:0; response_revision:1098; }","duration":"105.267898ms","start":"2026-08-24T15:42:27.016994Z","end":"2026-08-24T15:42:27.122262Z","steps":["trace[305421313] 'agreement among raft nodes before linearized reading' (duration: 105.149927ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:28.374712Z","caller":"traceutil/trace.go:171","msg":"trace[1021515461] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"253.592267ms","start":"2026-08-24T15:42:28.121074Z","end":"2026-08-24T15:42:28.374666Z","steps":["trace[1021515461] 'process raft request' (duration: 253.017958ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:28.384767Z","caller":"traceutil/trace.go:171","msg":"trace[583539604] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"123.93919ms","start":"2026-08-24T15:42:28.260785Z","end":"2026-08-24T15:42:28.384724Z","steps":["trace[583539604] 'process raft request' (duration: 123.562465ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:34.660095Z","caller":"traceutil/trace.go:171","msg":"trace[542162935] transaction","detail":"{read_only:false; response_revision:1578; number_of_response:1; }","duration":"134.087506ms","start":"2026-08-24T15:42:34.525972Z","end":"2026-08-24T15:42:34.660059Z","steps":["trace[542162935] 'process raft request' (duration: 97.006396ms)","trace[542162935] 'compare' (duration: 36.551763ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:42:35.661791Z","caller":"traceutil/trace.go:171","msg":"trace[2133063391] transaction","detail":"{read_only:false; response_revision:1586; number_of_response:1; }","duration":"144.05596ms","start":"2026-08-24T15:42:35.517675Z","end":"2026-08-24T15:42:35.661731Z","steps":["trace[2133063391] 'process raft request' (duration: 143.760256ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:35.661758Z","caller":"traceutil/trace.go:171","msg":"trace[2143104945] transaction","detail":"{read_only:false; response_revision:1587; number_of_response:1; }","duration":"143.934239ms","start":"2026-08-24T15:42:35.517791Z","end":"2026-08-24T15:42:35.661725Z","steps":["trace[2143104945] 'process raft request' (duration: 143.843948ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:35.662028Z","caller":"traceutil/trace.go:171","msg":"trace[1809107300] transaction","detail":"{read_only:false; response_revision:1588; number_of_response:1; }","duration":"143.378711ms","start":"2026-08-24T15:42:35.518592Z","end":"2026-08-24T15:42:35.66197Z","steps":["trace[1809107300] 'process raft request' (duration: 143.085797ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:36.069357Z","caller":"traceutil/trace.go:171","msg":"trace[305687372] linearizableReadLoop","detail":"{readStateIndex:1642; appliedIndex:1641; }","duration":"155.460804ms","start":"2026-08-24T15:42:35.91386Z","end":"2026-08-24T15:42:36.069321Z","steps":["trace[305687372] 'read index received' (duration: 137.971199ms)","trace[305687372] 'applied index is now lower than readState.Index' (duration: 17.487355ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:42:36.069385Z","caller":"traceutil/trace.go:171","msg":"trace[858049839] transaction","detail":"{read_only:false; response_revision:1589; number_of_response:1; }","duration":"361.625053ms","start":"2026-08-24T15:42:35.70773Z","end":"2026-08-24T15:42:36.069355Z","steps":["trace[858049839] 'process raft request' (duration: 344.159148ms)","trace[858049839] 'compare' (duration: 17.0987ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:42:36.069489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.616796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:42:36.069534Z","caller":"traceutil/trace.go:171","msg":"trace[129699895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1589; }","duration":"155.715598ms","start":"2026-08-24T15:42:35.913805Z","end":"2026-08-24T15:42:36.069521Z","steps":["trace[129699895] 'agreement among raft nodes before linearized reading' (duration: 155.608695ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:42:36.069537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:42:35.707706Z","time spent":"361.755674ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:42:36.792434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.799668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:42:36.792569Z","caller":"traceutil/trace.go:171","msg":"trace[789903979] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1589; }","duration":"408.9905ms","start":"2026-08-24T15:42:36.38355Z","end":"2026-08-24T15:42:36.792541Z","steps":["trace[789903979] 'count revisions from in-memory index tree' (duration: 408.736557ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:42:36.792623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:42:36.383536Z","time spent":"409.073692ms","remote":"127.0.0.1:54274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-08-24T15:42:37.693379Z","caller":"traceutil/trace.go:171","msg":"trace[1809726586] transaction","detail":"{read_only:false; response_revision:1591; number_of_response:1; }","duration":"108.050985ms","start":"2026-08-24T15:42:37.585274Z","end":"2026-08-24T15:42:37.693325Z","steps":["trace[1809726586] 'process raft request' (duration: 107.809202ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:42:40.22487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.601061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4893"} {"level":"warn","ts":"2026-08-24T15:42:40.22493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.921675ms","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-08-24T15:42:40.224984Z","caller":"traceutil/trace.go:171","msg":"trace[1399972163] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1628; }","duration":"234.753093ms","start":"2026-08-24T15:42:39.990207Z","end":"2026-08-24T15:42:40.22496Z","steps":["trace[1399972163] 'range keys from in-memory index tree' (duration: 234.441339ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:40.225052Z","caller":"traceutil/trace.go:171","msg":"trace[1021514959] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1628; }","duration":"140.072617ms","start":"2026-08-24T15:42:40.084948Z","end":"2026-08-24T15:42:40.225021Z","steps":["trace[1021514959] 'range keys from in-memory index tree' (duration: 139.685462ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:41.398104Z","caller":"traceutil/trace.go:171","msg":"trace[2070559643] transaction","detail":"{read_only:false; response_revision:1652; number_of_response:1; }","duration":"149.612226ms","start":"2026-08-24T15:42:41.248457Z","end":"2026-08-24T15:42:41.398069Z","steps":["trace[2070559643] 'process raft request' (duration: 149.388593ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:41.468819Z","caller":"traceutil/trace.go:171","msg":"trace[367429163] transaction","detail":"{read_only:false; response_revision:1653; number_of_response:1; }","duration":"212.511283ms","start":"2026-08-24T15:42:41.256269Z","end":"2026-08-24T15:42:41.46878Z","steps":["trace[367429163] 'process raft request' (duration: 212.218269ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:41.847713Z","caller":"traceutil/trace.go:171","msg":"trace[356292925] linearizableReadLoop","detail":"{readStateIndex:1709; appliedIndex:1708; }","duration":"141.014289ms","start":"2026-08-24T15:42:41.706671Z","end":"2026-08-24T15:42:41.847686Z","steps":["trace[356292925] 'read index received' (duration: 140.841327ms)","trace[356292925] 'applied index is now lower than readState.Index' (duration: 170.942µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:42:41.84787Z","caller":"traceutil/trace.go:171","msg":"trace[1876447292] transaction","detail":"{read_only:false; response_revision:1654; number_of_response:1; }","duration":"438.136873ms","start":"2026-08-24T15:42:41.409721Z","end":"2026-08-24T15:42:41.847858Z","steps":["trace[1876447292] 'process raft request' (duration: 437.810259ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:42:41.848004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:42:41.409662Z","time spent":"438.249515ms","remote":"127.0.0.1:54298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:42:41.848192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.005863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-24T15:42:41.848396Z","caller":"traceutil/trace.go:171","msg":"trace[1865745167] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1654; }","duration":"125.244297ms","start":"2026-08-24T15:42:41.723122Z","end":"2026-08-24T15:42:41.848367Z","steps":["trace[1865745167] 'agreement among raft nodes before linearized reading' (duration: 124.883732ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:42:41.848549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.358302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-24T15:42:41.848592Z","caller":"traceutil/trace.go:171","msg":"trace[679064294] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1654; }","duration":"123.416473ms","start":"2026-08-24T15:42:41.725164Z","end":"2026-08-24T15:42:41.848581Z","steps":["trace[679064294] 'agreement among raft nodes before linearized reading' (duration: 123.273381ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:42:41.848734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.074445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-24T15:42:41.848758Z","caller":"traceutil/trace.go:171","msg":"trace[1380843029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1654; }","duration":"142.146755ms","start":"2026-08-24T15:42:41.706605Z","end":"2026-08-24T15:42:41.848752Z","steps":["trace[1380843029] 'agreement among raft nodes before linearized reading' (duration: 142.078344ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:42:41.848757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.963461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-24T15:42:41.848854Z","caller":"traceutil/trace.go:171","msg":"trace[1043041778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1654; }","duration":"124.097383ms","start":"2026-08-24T15:42:41.724736Z","end":"2026-08-24T15:42:41.848833Z","steps":["trace[1043041778] 'agreement among raft nodes before linearized reading' (duration: 123.820819ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:42.015965Z","caller":"traceutil/trace.go:171","msg":"trace[1780770523] transaction","detail":"{read_only:false; response_revision:1656; number_of_response:1; }","duration":"163.082117ms","start":"2026-08-24T15:42:41.852853Z","end":"2026-08-24T15:42:42.015935Z","steps":["trace[1780770523] 'process raft request' (duration: 162.962585ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:42.016217Z","caller":"traceutil/trace.go:171","msg":"trace[1638054983] transaction","detail":"{read_only:false; response_revision:1655; number_of_response:1; }","duration":"163.776636ms","start":"2026-08-24T15:42:41.852423Z","end":"2026-08-24T15:42:42.0162Z","steps":["trace[1638054983] 'process raft request' (duration: 115.323614ms)","trace[1638054983] 'compare' (duration: 47.887715ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:42:42.027427Z","caller":"traceutil/trace.go:171","msg":"trace[308864902] transaction","detail":"{read_only:false; response_revision:1657; number_of_response:1; }","duration":"173.582448ms","start":"2026-08-24T15:42:41.853769Z","end":"2026-08-24T15:42:42.027351Z","steps":["trace[308864902] 'process raft request' (duration: 162.112604ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:42.0276Z","caller":"traceutil/trace.go:171","msg":"trace[1527670431] transaction","detail":"{read_only:false; response_revision:1658; number_of_response:1; }","duration":"173.643679ms","start":"2026-08-24T15:42:41.853917Z","end":"2026-08-24T15:42:42.02756Z","steps":["trace[1527670431] 'process raft request' (duration: 162.5704ms)","trace[1527670431] 'store kv pair into bolt db' {req_type:put; key:/registry/leases/kube-system/plndr-svcs-lock; req_size:419; } (duration: 10.636764ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:42:44.274909Z","caller":"traceutil/trace.go:171","msg":"trace[365345260] linearizableReadLoop","detail":"{readStateIndex:1715; appliedIndex:1714; }","duration":"360.963273ms","start":"2026-08-24T15:42:43.913925Z","end":"2026-08-24T15:42:44.274888Z","steps":["trace[365345260] 'read index received' (duration: 360.74813ms)","trace[365345260] 'applied index is now lower than readState.Index' (duration: 214.043µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:42:44.275023Z","caller":"traceutil/trace.go:171","msg":"trace[1749884661] transaction","detail":"{read_only:false; response_revision:1660; number_of_response:1; }","duration":"795.998756ms","start":"2026-08-24T15:42:43.478991Z","end":"2026-08-24T15:42:44.27499Z","steps":["trace[1749884661] 'process raft request' (duration: 795.748702ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:42:44.27513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.794212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-24T15:42:44.275173Z","caller":"traceutil/trace.go:171","msg":"trace[359405079] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1660; }","duration":"242.895383ms","start":"2026-08-24T15:42:44.032265Z","end":"2026-08-24T15:42:44.275161Z","steps":["trace[359405079] 'agreement among raft nodes before linearized reading' (duration: 242.744611ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:42:44.275185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:42:43.478953Z","time spent":"796.142038ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:42:44.275214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.237517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:42:44.27533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.421407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-24T15:42:44.275382Z","caller":"traceutil/trace.go:171","msg":"trace[1566570547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1660; }","duration":"242.521948ms","start":"2026-08-24T15:42:44.032845Z","end":"2026-08-24T15:42:44.275367Z","steps":["trace[1566570547] 'agreement among raft nodes before linearized reading' (duration: 242.254125ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:44.275381Z","caller":"traceutil/trace.go:171","msg":"trace[1823465781] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1660; }","duration":"361.500261ms","start":"2026-08-24T15:42:43.913846Z","end":"2026-08-24T15:42:44.275347Z","steps":["trace[1823465781] 'agreement among raft nodes before linearized reading' (duration: 361.180457ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:42:44.275434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.061145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-24T15:42:44.275465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.869572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-24T15:42:44.275546Z","caller":"traceutil/trace.go:171","msg":"trace[958806608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1660; }","duration":"243.196627ms","start":"2026-08-24T15:42:44.032327Z","end":"2026-08-24T15:42:44.275523Z","steps":["trace[958806608] 'agreement among raft nodes before linearized reading' (duration: 242.878112ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:44.275586Z","caller":"traceutil/trace.go:171","msg":"trace[1395471046] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1660; }","duration":"243.016035ms","start":"2026-08-24T15:42:44.03254Z","end":"2026-08-24T15:42:44.275556Z","steps":["trace[1395471046] 'agreement among raft nodes before linearized reading' (duration: 242.61389ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:42:44.275451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:42:43.913809Z","time spent":"361.629682ms","remote":"127.0.0.1:43306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-24T15:43:01.342136Z","caller":"traceutil/trace.go:171","msg":"trace[743584237] linearizableReadLoop","detail":"{readStateIndex:1797; appliedIndex:1796; }","duration":"465.168367ms","start":"2026-08-24T15:43:00.876942Z","end":"2026-08-24T15:43:01.34211Z","steps":["trace[743584237] 'read index received' (duration: 464.950585ms)","trace[743584237] 'applied index is now lower than readState.Index' (duration: 216.842µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:43:01.342381Z","caller":"traceutil/trace.go:171","msg":"trace[516398843] transaction","detail":"{read_only:false; response_revision:1738; number_of_response:1; }","duration":"595.466343ms","start":"2026-08-24T15:43:00.746896Z","end":"2026-08-24T15:43:01.342363Z","steps":["trace[516398843] 'process raft request' (duration: 594.995087ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:43:01.342451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.064042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:43:01.342489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:43:00.746872Z","time spent":"595.554194ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:43:01.342518Z","caller":"traceutil/trace.go:171","msg":"trace[362386736] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1738; }","duration":"455.175032ms","start":"2026-08-24T15:43:00.887319Z","end":"2026-08-24T15:43:01.342494Z","steps":["trace[362386736] 'agreement among raft nodes before linearized reading' (duration: 455.076961ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:43:01.342561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.584718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:43:01.342588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:43:00.887273Z","time spent":"455.291474ms","remote":"127.0.0.1:41570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:43:01.342562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.580703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-tt4g7\" ","response":"range_response_count:1 size:4153"} {"level":"info","ts":"2026-08-24T15:43:01.34264Z","caller":"traceutil/trace.go:171","msg":"trace[6955323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1738; }","duration":"429.70841ms","start":"2026-08-24T15:43:00.912913Z","end":"2026-08-24T15:43:01.342621Z","steps":["trace[6955323] 'agreement among raft nodes before linearized reading' (duration: 429.537388ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:43:01.342681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:43:00.912892Z","time spent":"429.781081ms","remote":"127.0.0.1:43306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-24T15:43:01.342682Z","caller":"traceutil/trace.go:171","msg":"trace[578934677] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-create-tt4g7; range_end:; response_count:1; response_revision:1738; }","duration":"465.768386ms","start":"2026-08-24T15:43:00.876887Z","end":"2026-08-24T15:43:01.342655Z","steps":["trace[578934677] 'agreement among raft nodes before linearized reading' (duration: 465.435901ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:43:01.34274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:43:00.876865Z","time spent":"465.865507ms","remote":"127.0.0.1:43484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":4177,"request content":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-tt4g7\" "} {"level":"info","ts":"2026-08-24T15:43:01.571952Z","caller":"traceutil/trace.go:171","msg":"trace[563395192] transaction","detail":"{read_only:false; response_revision:1739; number_of_response:1; }","duration":"220.173606ms","start":"2026-08-24T15:43:01.351759Z","end":"2026-08-24T15:43:01.571933Z","steps":["trace[563395192] 'process raft request' (duration: 220.024884ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:43:02.583385Z","caller":"traceutil/trace.go:171","msg":"trace[426945794] transaction","detail":"{read_only:false; response_revision:1744; number_of_response:1; }","duration":"152.821548ms","start":"2026-08-24T15:43:02.430536Z","end":"2026-08-24T15:43:02.583357Z","steps":["trace[426945794] 'process raft request' (duration: 152.338292ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:43:02.583839Z","caller":"traceutil/trace.go:171","msg":"trace[1314908288] transaction","detail":"{read_only:false; response_revision:1745; number_of_response:1; }","duration":"152.397113ms","start":"2026-08-24T15:43:02.431405Z","end":"2026-08-24T15:43:02.583802Z","steps":["trace[1314908288] 'process raft request' (duration: 151.842906ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:43:02.887319Z","caller":"traceutil/trace.go:171","msg":"trace[1600623578] linearizableReadLoop","detail":"{readStateIndex:1805; appliedIndex:1804; }","duration":"295.835796ms","start":"2026-08-24T15:43:02.591435Z","end":"2026-08-24T15:43:02.887271Z","steps":["trace[1600623578] 'read index received' (duration: 295.684164ms)","trace[1600623578] 'applied index is now lower than readState.Index' (duration: 150.402µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:43:02.88745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.000368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:43:02.887488Z","caller":"traceutil/trace.go:171","msg":"trace[1731729812] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1746; }","duration":"296.08754ms","start":"2026-08-24T15:43:02.591388Z","end":"2026-08-24T15:43:02.887475Z","steps":["trace[1731729812] 'agreement among raft nodes before linearized reading' (duration: 296.012679ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:43:02.887522Z","caller":"traceutil/trace.go:171","msg":"trace[1384179300] transaction","detail":"{read_only:false; response_revision:1746; number_of_response:1; }","duration":"306.52445ms","start":"2026-08-24T15:43:02.580833Z","end":"2026-08-24T15:43:02.887357Z","steps":["trace[1384179300] 'process raft request' (duration: 306.280517ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:43:02.887686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:43:02.580812Z","time spent":"306.787533ms","remote":"127.0.0.1:43532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3524,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:43:03.032029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.471898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:43:03.032113Z","caller":"traceutil/trace.go:171","msg":"trace[1988728475] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1746; }","duration":"120.092568ms","start":"2026-08-24T15:43:02.912002Z","end":"2026-08-24T15:43:03.032094Z","steps":["trace[1988728475] 'range keys from in-memory index tree' (duration: 119.353837ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:43:04.715666Z","caller":"traceutil/trace.go:171","msg":"trace[353475861] linearizableReadLoop","detail":"{readStateIndex:1806; appliedIndex:1805; }","duration":"307.841068ms","start":"2026-08-24T15:43:04.407806Z","end":"2026-08-24T15:43:04.715647Z","steps":["trace[353475861] 'read index received' (duration: 307.725216ms)","trace[353475861] 'applied index is now lower than readState.Index' (duration: 115.122µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:43:04.715777Z","caller":"traceutil/trace.go:171","msg":"trace[989285801] transaction","detail":"{read_only:false; response_revision:1747; number_of_response:1; }","duration":"330.338511ms","start":"2026-08-24T15:43:04.38543Z","end":"2026-08-24T15:43:04.715769Z","steps":["trace[989285801] 'process raft request' (duration: 330.099877ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:43:04.715893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:43:04.385405Z","time spent":"330.396391ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:43:04.716059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.273423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-24T15:43:04.716088Z","caller":"traceutil/trace.go:171","msg":"trace[1230990625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1747; }","duration":"308.359486ms","start":"2026-08-24T15:43:04.407721Z","end":"2026-08-24T15:43:04.71608Z","steps":["trace[1230990625] 'agreement among raft nodes before linearized reading' (duration: 308.265663ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:43:04.716108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:43:04.407688Z","time spent":"308.416096ms","remote":"127.0.0.1:43572","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-08-24T15:43:04.716205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.456486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-24T15:43:04.716234Z","caller":"traceutil/trace.go:171","msg":"trace[188507593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1747; }","duration":"285.526087ms","start":"2026-08-24T15:43:04.430701Z","end":"2026-08-24T15:43:04.716228Z","steps":["trace[188507593] 'agreement among raft nodes before linearized reading' (duration: 285.456626ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:43:04.716319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.201718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-24T15:43:04.716335Z","caller":"traceutil/trace.go:171","msg":"trace[245519301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1747; }","duration":"128.261958ms","start":"2026-08-24T15:43:04.588068Z","end":"2026-08-24T15:43:04.71633Z","steps":["trace[245519301] 'agreement among raft nodes before linearized reading' (duration: 128.213767ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:43:04.716411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.901894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:43:04.716425Z","caller":"traceutil/trace.go:171","msg":"trace[831835883] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1747; }","duration":"101.971054ms","start":"2026-08-24T15:43:04.61445Z","end":"2026-08-24T15:43:04.716421Z","steps":["trace[831835883] 'agreement among raft nodes before linearized reading' (duration: 101.922134ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:43:04.716445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.64611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-24T15:43:04.716487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.997872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-24T15:43:04.716516Z","caller":"traceutil/trace.go:171","msg":"trace[191324541] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1747; }","duration":"127.791582ms","start":"2026-08-24T15:43:04.588709Z","end":"2026-08-24T15:43:04.716501Z","steps":["trace[191324541] 'agreement among raft nodes before linearized reading' (duration: 127.512559ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:43:04.716543Z","caller":"traceutil/trace.go:171","msg":"trace[1619757610] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1747; }","duration":"274.113544ms","start":"2026-08-24T15:43:04.442414Z","end":"2026-08-24T15:43:04.716527Z","steps":["trace[1619757610] 'agreement among raft nodes before linearized reading' (duration: 273.938411ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:43:04.876434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.742754ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-24T15:43:04.905091Z","caller":"traceutil/trace.go:171","msg":"trace[468905935] transaction","detail":"{read_only:false; response_revision:1748; number_of_response:1; }","duration":"183.724866ms","start":"2026-08-24T15:43:04.721341Z","end":"2026-08-24T15:43:04.905066Z","steps":["trace[468905935] 'process raft request' (duration: 183.513823ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:43:04.905487Z","caller":"traceutil/trace.go:171","msg":"trace[1399525709] transaction","detail":"{read_only:false; response_revision:1752; number_of_response:1; }","duration":"183.560053ms","start":"2026-08-24T15:43:04.721893Z","end":"2026-08-24T15:43:04.905453Z","steps":["trace[1399525709] 'process raft request' (duration: 183.509952ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:43:04.905453Z","caller":"traceutil/trace.go:171","msg":"trace[755707455] transaction","detail":"{read_only:false; response_revision:1749; number_of_response:1; }","duration":"183.922269ms","start":"2026-08-24T15:43:04.721469Z","end":"2026-08-24T15:43:04.905391Z","steps":["trace[755707455] 'process raft request' (duration: 183.543254ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:43:04.905552Z","caller":"traceutil/trace.go:171","msg":"trace[187151457] transaction","detail":"{read_only:false; response_revision:1751; number_of_response:1; }","duration":"183.701235ms","start":"2026-08-24T15:43:04.721838Z","end":"2026-08-24T15:43:04.905539Z","steps":["trace[187151457] 'process raft request' (duration: 183.519133ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:43:04.905546Z","caller":"traceutil/trace.go:171","msg":"trace[917782106] transaction","detail":"{read_only:false; response_revision:1750; number_of_response:1; }","duration":"183.673175ms","start":"2026-08-24T15:43:04.721835Z","end":"2026-08-24T15:43:04.905508Z","steps":["trace[917782106] 'process raft request' (duration: 183.449382ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:43:11.815857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.675001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:43:11.815958Z","caller":"traceutil/trace.go:171","msg":"trace[710418425] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1878; }","duration":"127.852663ms","start":"2026-08-24T15:43:11.688086Z","end":"2026-08-24T15:43:11.815938Z","steps":["trace[710418425] 'count revisions from in-memory index tree' (duration: 127.554699ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:43:41.58358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.235406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:43:41.584061Z","caller":"traceutil/trace.go:171","msg":"trace[821372981] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"439.15773ms","start":"2026-08-24T15:43:41.144852Z","end":"2026-08-24T15:43:41.58401Z","steps":["trace[821372981] 'process raft request' (duration: 285.380532ms)","trace[821372981] 'compare' (duration: 152.96451ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:43:41.584151Z","caller":"traceutil/trace.go:171","msg":"trace[1983515517] transaction","detail":"{read_only:false; response_revision:2004; number_of_response:1; }","duration":"439.195242ms","start":"2026-08-24T15:43:41.14493Z","end":"2026-08-24T15:43:41.584126Z","steps":["trace[1983515517] 'process raft request' (duration: 438.794663ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:43:41.58425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:43:41.144825Z","time spent":"439.320284ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:43:41.58428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:43:41.144914Z","time spent":"439.279993ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:43:41.58429Z","caller":"traceutil/trace.go:171","msg":"trace[167618831] transaction","detail":"{read_only:false; response_revision:2005; number_of_response:1; }","duration":"439.350175ms","start":"2026-08-24T15:43:41.144917Z","end":"2026-08-24T15:43:41.584267Z","steps":["trace[167618831] 'process raft request' (duration: 439.1354ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:43:41.584453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:43:41.144756Z","time spent":"439.642122ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:43:41.584576Z","caller":"traceutil/trace.go:171","msg":"trace[1937191949] linearizableReadLoop","detail":"{readStateIndex:2076; appliedIndex:2072; }","duration":"319.115285ms","start":"2026-08-24T15:43:41.265445Z","end":"2026-08-24T15:43:41.58456Z","steps":["trace[1937191949] 'read index received' (duration: 164.788034ms)","trace[1937191949] 'applied index is now lower than readState.Index' (duration: 154.325821ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:43:41.584608Z","caller":"traceutil/trace.go:171","msg":"trace[1965748740] transaction","detail":"{read_only:false; response_revision:2006; number_of_response:1; }","duration":"439.5252ms","start":"2026-08-24T15:43:41.145066Z","end":"2026-08-24T15:43:41.584591Z","steps":["trace[1965748740] 'process raft request' (duration: 439.154971ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:43:41.584701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:43:41.145057Z","time spent":"439.594521ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:43:41.584767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.319549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:12268"} {"level":"info","ts":"2026-08-24T15:43:41.584811Z","caller":"traceutil/trace.go:171","msg":"trace[1961990709] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:2006; }","duration":"319.408431ms","start":"2026-08-24T15:43:41.265388Z","end":"2026-08-24T15:43:41.584796Z","steps":["trace[1961990709] 'agreement among raft nodes before linearized reading' (duration: 319.221697ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:43:41.584846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:43:41.265359Z","time spent":"319.478953ms","remote":"127.0.0.1:43478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":12292,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-08-24T15:44:00.37243Z","caller":"traceutil/trace.go:171","msg":"trace[1607384737] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"168.401277ms","start":"2026-08-24T15:44:00.204007Z","end":"2026-08-24T15:44:00.372409Z","steps":["trace[1607384737] 'process raft request' (duration: 168.262734ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:25.040668Z","caller":"traceutil/trace.go:171","msg":"trace[110729793] linearizableReadLoop","detail":"{readStateIndex:2554; appliedIndex:2553; }","duration":"127.219958ms","start":"2026-08-24T15:45:24.913411Z","end":"2026-08-24T15:45:25.040631Z","steps":["trace[110729793] 'read index received' (duration: 32.756378ms)","trace[110729793] 'applied index is now lower than readState.Index' (duration: 94.46095ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:45:25.040851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.422812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:45:25.040884Z","caller":"traceutil/trace.go:171","msg":"trace[412449693] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2462; }","duration":"127.518005ms","start":"2026-08-24T15:45:24.913356Z","end":"2026-08-24T15:45:25.040874Z","steps":["trace[412449693] 'agreement among raft nodes before linearized reading' (duration: 127.418142ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:25.787108Z","caller":"traceutil/trace.go:171","msg":"trace[1056301680] transaction","detail":"{read_only:false; response_revision:2463; number_of_response:1; }","duration":"235.538114ms","start":"2026-08-24T15:45:25.551522Z","end":"2026-08-24T15:45:25.78706Z","steps":["trace[1056301680] 'process raft request' (duration: 235.169515ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:26.281772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.452665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:45:26.281867Z","caller":"traceutil/trace.go:171","msg":"trace[442972140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2463; }","duration":"367.549187ms","start":"2026-08-24T15:45:25.914293Z","end":"2026-08-24T15:45:26.281842Z","steps":["trace[442972140] 'range keys from in-memory index tree' (duration: 367.356983ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:26.281902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:25.914236Z","time spent":"367.657131ms","remote":"127.0.0.1:43306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T15:45:26.281919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.855425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-24T15:45:26.282273Z","caller":"traceutil/trace.go:171","msg":"trace[526725419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2463; }","duration":"430.199833ms","start":"2026-08-24T15:45:25.852Z","end":"2026-08-24T15:45:26.2822Z","steps":["trace[526725419] 'range keys from in-memory index tree' (duration: 429.67044ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:26.282387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:25.851977Z","time spent":"430.353256ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T15:45:26.778249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.521193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:45:26.778355Z","caller":"traceutil/trace.go:171","msg":"trace[377497484] linearizableReadLoop","detail":"{readStateIndex:2557; appliedIndex:2556; }","duration":"470.904863ms","start":"2026-08-24T15:45:26.30743Z","end":"2026-08-24T15:45:26.778335Z","steps":["trace[377497484] 'read index received' (duration: 272.230777ms)","trace[377497484] 'applied index is now lower than readState.Index' (duration: 198.672326ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:45:26.778408Z","caller":"traceutil/trace.go:171","msg":"trace[1229097430] transaction","detail":"{read_only:false; response_revision:2465; number_of_response:1; }","duration":"476.858318ms","start":"2026-08-24T15:45:26.301534Z","end":"2026-08-24T15:45:26.778393Z","steps":["trace[1229097430] 'process raft request' (duration: 278.106629ms)","trace[1229097430] 'compare' (duration: 198.274487ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:45:26.77848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:26.301517Z","time spent":"476.92348ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:45:26.778548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.737181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-24T15:45:26.778577Z","caller":"traceutil/trace.go:171","msg":"trace[332610652] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2465; }","duration":"399.797783ms","start":"2026-08-24T15:45:26.378771Z","end":"2026-08-24T15:45:26.778568Z","steps":["trace[332610652] 'agreement among raft nodes before linearized reading' (duration: 399.66949ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:26.778605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:26.37875Z","time spent":"399.848434ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-24T15:45:26.778753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.898255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-24T15:45:26.778789Z","caller":"traceutil/trace.go:171","msg":"trace[970161741] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2465; }","duration":"390.990247ms","start":"2026-08-24T15:45:26.38779Z","end":"2026-08-24T15:45:26.77878Z","steps":["trace[970161741] 'agreement among raft nodes before linearized reading' (duration: 390.895195ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:26.778818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:26.387774Z","time spent":"391.036289ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-24T15:45:26.778951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.418825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-24T15:45:26.779108Z","caller":"traceutil/trace.go:171","msg":"trace[1839296768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2465; }","duration":"471.676321ms","start":"2026-08-24T15:45:26.307402Z","end":"2026-08-24T15:45:26.779079Z","steps":["trace[1839296768] 'agreement among raft nodes before linearized reading' (duration: 470.996045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:26.77916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:26.307391Z","time spent":"471.755474ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-24T15:45:27.093024Z","caller":"traceutil/trace.go:171","msg":"trace[1409427178] transaction","detail":"{read_only:false; response_revision:2467; number_of_response:1; }","duration":"309.442932ms","start":"2026-08-24T15:45:26.783567Z","end":"2026-08-24T15:45:27.09301Z","steps":["trace[1409427178] 'process raft request' (duration: 309.206446ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:27.093158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:26.783536Z","time spent":"309.541174ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:45:27.092925Z","caller":"traceutil/trace.go:171","msg":"trace[900180660] transaction","detail":"{read_only:false; response_revision:2466; number_of_response:1; }","duration":"309.309039ms","start":"2026-08-24T15:45:26.783535Z","end":"2026-08-24T15:45:27.092844Z","steps":["trace[900180660] 'process raft request' (duration: 309.085813ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:27.093365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:26.783497Z","time spent":"309.835912ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:45:27.325483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.221256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:45:27.325751Z","caller":"traceutil/trace.go:171","msg":"trace[756298282] transaction","detail":"{read_only:false; response_revision:2468; number_of_response:1; }","duration":"541.714267ms","start":"2026-08-24T15:45:26.783998Z","end":"2026-08-24T15:45:27.325713Z","steps":["trace[756298282] 'process raft request' (duration: 435.124452ms)","trace[756298282] 'compare' (duration: 106.054762ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:45:27.325812Z","caller":"traceutil/trace.go:171","msg":"trace[1344462528] transaction","detail":"{read_only:false; response_revision:2469; number_of_response:1; }","duration":"220.000415ms","start":"2026-08-24T15:45:27.105785Z","end":"2026-08-24T15:45:27.325785Z","steps":["trace[1344462528] 'process raft request' (duration: 219.860902ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:27.325917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:26.783983Z","time spent":"541.843819ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:45:27.519649Z","caller":"traceutil/trace.go:171","msg":"trace[822151665] linearizableReadLoop","detail":"{readStateIndex:2562; appliedIndex:2561; }","duration":"136.458922ms","start":"2026-08-24T15:45:27.38315Z","end":"2026-08-24T15:45:27.519609Z","steps":["trace[822151665] 'read index received' (duration: 128.480488ms)","trace[822151665] 'applied index is now lower than readState.Index' (duration: 7.976984ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:45:27.519832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.646456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:45:27.519863Z","caller":"traceutil/trace.go:171","msg":"trace[657688542] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:2470; }","duration":"136.759259ms","start":"2026-08-24T15:45:27.383093Z","end":"2026-08-24T15:45:27.519852Z","steps":["trace[657688542] 'agreement among raft nodes before linearized reading' (duration: 136.648396ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:27.519812Z","caller":"traceutil/trace.go:171","msg":"trace[822713473] transaction","detail":"{read_only:false; response_revision:2470; number_of_response:1; }","duration":"205.833771ms","start":"2026-08-24T15:45:27.313909Z","end":"2026-08-24T15:45:27.519743Z","steps":["trace[822713473] 'process raft request' (duration: 197.693013ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:33.480922Z","caller":"traceutil/trace.go:171","msg":"trace[1938872493] transaction","detail":"{read_only:false; response_revision:2491; number_of_response:1; }","duration":"122.812901ms","start":"2026-08-24T15:45:33.358036Z","end":"2026-08-24T15:45:33.480849Z","steps":["trace[1938872493] 'process raft request' (duration: 122.585204ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:34.034176Z","caller":"traceutil/trace.go:171","msg":"trace[956887456] linearizableReadLoop","detail":"{readStateIndex:2585; appliedIndex:2584; }","duration":"120.693238ms","start":"2026-08-24T15:45:33.913463Z","end":"2026-08-24T15:45:34.034156Z","steps":["trace[956887456] 'read index received' (duration: 120.514263ms)","trace[956887456] 'applied index is now lower than readState.Index' (duration: 178.225µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:45:34.034237Z","caller":"traceutil/trace.go:171","msg":"trace[693394003] transaction","detail":"{read_only:false; response_revision:2492; number_of_response:1; }","duration":"144.134698ms","start":"2026-08-24T15:45:33.890074Z","end":"2026-08-24T15:45:34.034209Z","steps":["trace[693394003] 'process raft request' (duration: 143.947373ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:34.034282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.80845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:45:34.034306Z","caller":"traceutil/trace.go:171","msg":"trace[1833054080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2492; }","duration":"120.890622ms","start":"2026-08-24T15:45:33.913407Z","end":"2026-08-24T15:45:34.034298Z","steps":["trace[1833054080] 'agreement among raft nodes before linearized reading' (duration: 120.826841ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:49:57.214876Z","caller":"traceutil/trace.go:171","msg":"trace[1421787737] transaction","detail":"{read_only:false; response_revision:3426; number_of_response:1; }","duration":"105.33842ms","start":"2026-08-24T15:49:57.109505Z","end":"2026-08-24T15:49:57.214844Z","steps":["trace[1421787737] 'process raft request' (duration: 105.160355ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:49:59.644228Z","caller":"traceutil/trace.go:171","msg":"trace[789440129] transaction","detail":"{read_only:false; response_revision:3532; number_of_response:1; }","duration":"142.391251ms","start":"2026-08-24T15:49:59.501806Z","end":"2026-08-24T15:49:59.644197Z","steps":["trace[789440129] 'process raft request' (duration: 142.020171ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:50:11.07188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.317651ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:50:11.072185Z","caller":"traceutil/trace.go:171","msg":"trace[1982501610] linearizableReadLoop","detail":"{readStateIndex:3927; appliedIndex:3925; }","duration":"403.555544ms","start":"2026-08-24T15:50:10.668607Z","end":"2026-08-24T15:50:11.072162Z","steps":["trace[1982501610] 'read index received' (duration: 101.840863ms)","trace[1982501610] 'applied index is now lower than readState.Index' (duration: 301.712881ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:50:11.07231Z","caller":"traceutil/trace.go:171","msg":"trace[1007366647] transaction","detail":"{read_only:false; response_revision:3750; number_of_response:1; }","duration":"638.393782ms","start":"2026-08-24T15:50:10.433896Z","end":"2026-08-24T15:50:11.07229Z","steps":["trace[1007366647] 'process raft request' (duration: 336.555687ms)","trace[1007366647] 'compare' (duration: 300.84367ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:50:11.072372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:50:10.433873Z","time spent":"638.469824ms","remote":"127.0.0.1:43468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":671,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:50:11.072404Z","caller":"traceutil/trace.go:171","msg":"trace[1369092045] transaction","detail":"{read_only:false; response_revision:3751; number_of_response:1; }","duration":"636.461695ms","start":"2026-08-24T15:50:10.435911Z","end":"2026-08-24T15:50:11.072373Z","steps":["trace[1369092045] 'process raft request' (duration: 636.129316ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:50:11.072531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.323832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-08-24T15:50:11.072573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:50:10.435901Z","time spent":"636.594278ms","remote":"127.0.0.1:43588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1249,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:50:11.072616Z","caller":"traceutil/trace.go:171","msg":"trace[795908035] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3751; }","duration":"369.422995ms","start":"2026-08-24T15:50:10.703174Z","end":"2026-08-24T15:50:11.072596Z","steps":["trace[795908035] 'agreement among raft nodes before linearized reading' (duration: 369.150718ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:50:11.072617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.987575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-24T15:50:11.072675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:50:10.703114Z","time spent":"369.547338ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-24T15:50:11.072725Z","caller":"traceutil/trace.go:171","msg":"trace[1165225726] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3751; }","duration":"404.163279ms","start":"2026-08-24T15:50:10.668537Z","end":"2026-08-24T15:50:11.072701Z","steps":["trace[1165225726] 'agreement among raft nodes before linearized reading' (duration: 403.766659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:50:11.072782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:50:10.668508Z","time spent":"404.263532ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-24T15:50:11.26385Z","caller":"traceutil/trace.go:171","msg":"trace[2058549995] transaction","detail":"{read_only:false; response_revision:3754; number_of_response:1; }","duration":"103.826952ms","start":"2026-08-24T15:50:11.159991Z","end":"2026-08-24T15:50:11.263818Z","steps":["trace[2058549995] 'process raft request' (duration: 16.340507ms)","trace[2058549995] 'compare' (duration: 87.352861ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:50:16.143797Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2430} {"level":"info","ts":"2026-08-24T15:50:16.214349Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2430,"took":"69.420436ms","hash":3591069881,"current-db-size-bytes":36286464,"current-db-size":"36 MB","current-db-size-in-use-bytes":11825152,"current-db-size-in-use":"12 MB"} {"level":"info","ts":"2026-08-24T15:50:16.214442Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3591069881,"revision":2430,"compact-revision":-1} {"level":"info","ts":"2026-08-24T15:50:52.843422Z","caller":"traceutil/trace.go:171","msg":"trace[1491266096] transaction","detail":"{read_only:false; response_revision:4468; number_of_response:1; }","duration":"655.078236ms","start":"2026-08-24T15:50:52.18832Z","end":"2026-08-24T15:50:52.843398Z","steps":["trace[1491266096] 'process raft request' (duration: 654.930242ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:50:52.843546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:50:52.188303Z","time spent":"655.187039ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:50:52.861814Z","caller":"traceutil/trace.go:171","msg":"trace[1400719493] linearizableReadLoop","detail":"{readStateIndex:4668; appliedIndex:4666; }","duration":"493.684285ms","start":"2026-08-24T15:50:52.368105Z","end":"2026-08-24T15:50:52.861789Z","steps":["trace[1400719493] 'read index received' (duration: 475.214745ms)","trace[1400719493] 'applied index is now lower than readState.Index' (duration: 18.468199ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:50:52.861876Z","caller":"traceutil/trace.go:171","msg":"trace[616848613] transaction","detail":"{read_only:false; response_revision:4469; number_of_response:1; }","duration":"506.496073ms","start":"2026-08-24T15:50:52.355355Z","end":"2026-08-24T15:50:52.861851Z","steps":["trace[616848613] 'process raft request' (duration: 506.285528ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:50:52.862022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:50:52.355324Z","time spent":"506.630067ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:50:52.862068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.93076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-24T15:50:52.862148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.631452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6lt4p\" ","response":"range_response_count:1 size:6292"} {"level":"info","ts":"2026-08-24T15:50:52.862199Z","caller":"traceutil/trace.go:171","msg":"trace[510564958] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6lt4p; range_end:; response_count:1; response_revision:4469; }","duration":"170.732885ms","start":"2026-08-24T15:50:52.691452Z","end":"2026-08-24T15:50:52.862185Z","steps":["trace[510564958] 'agreement among raft nodes before linearized reading' (duration: 170.613152ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:50:52.862196Z","caller":"traceutil/trace.go:171","msg":"trace[1195771664] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:4469; }","duration":"494.118705ms","start":"2026-08-24T15:50:52.368038Z","end":"2026-08-24T15:50:52.862156Z","steps":["trace[1195771664] 'agreement among raft nodes before linearized reading' (duration: 493.884709ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:50:52.862249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.687797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-qbnk9\" ","response":"range_response_count:1 size:20232"} {"level":"warn","ts":"2026-08-24T15:50:52.862266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:50:52.368012Z","time spent":"494.236188ms","remote":"127.0.0.1:43694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-08-24T15:50:52.862325Z","caller":"traceutil/trace.go:171","msg":"trace[325620546] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-qbnk9; range_end:; response_count:1; response_revision:4469; }","duration":"148.8217ms","start":"2026-08-24T15:50:52.713485Z","end":"2026-08-24T15:50:52.862307Z","steps":["trace[325620546] 'agreement among raft nodes before linearized reading' (duration: 148.549784ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:50:52.862652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.914358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-24T15:50:52.862788Z","caller":"traceutil/trace.go:171","msg":"trace[1011504713] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4469; }","duration":"179.686947ms","start":"2026-08-24T15:50:52.683066Z","end":"2026-08-24T15:50:52.862753Z","steps":["trace[1011504713] 'agreement among raft nodes before linearized reading' (duration: 178.859397ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:51:14.722565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.860602ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:51:14.722833Z","caller":"traceutil/trace.go:171","msg":"trace[1576244165] transaction","detail":"{read_only:false; response_revision:4609; number_of_response:1; }","duration":"276.552408ms","start":"2026-08-24T15:51:14.44624Z","end":"2026-08-24T15:51:14.722792Z","steps":["trace[1576244165] 'process raft request' (duration: 95.369038ms)","trace[1576244165] 'compare' (duration: 180.707157ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:51:14.723085Z","caller":"traceutil/trace.go:171","msg":"trace[1884457921] transaction","detail":"{read_only:false; response_revision:4610; number_of_response:1; }","duration":"170.81746ms","start":"2026-08-24T15:51:14.552251Z","end":"2026-08-24T15:51:14.723068Z","steps":["trace[1884457921] 'process raft request' (duration: 170.444671ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:51:15.127593Z","caller":"traceutil/trace.go:171","msg":"trace[2129806056] linearizableReadLoop","detail":"{readStateIndex:4817; appliedIndex:4816; }","duration":"172.254746ms","start":"2026-08-24T15:51:14.955317Z","end":"2026-08-24T15:51:15.127572Z","steps":["trace[2129806056] 'read index received' (duration: 172.187464ms)","trace[2129806056] 'applied index is now lower than readState.Index' (duration: 66.422µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:51:15.127643Z","caller":"traceutil/trace.go:171","msg":"trace[1006672768] transaction","detail":"{read_only:false; response_revision:4612; number_of_response:1; }","duration":"192.45649ms","start":"2026-08-24T15:51:14.935168Z","end":"2026-08-24T15:51:15.127625Z","steps":["trace[1006672768] 'process raft request' (duration: 192.253885ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:51:15.127798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.463981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-24T15:51:15.127866Z","caller":"traceutil/trace.go:171","msg":"trace[75719469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4612; }","duration":"172.543272ms","start":"2026-08-24T15:51:14.955309Z","end":"2026-08-24T15:51:15.127852Z","steps":["trace[75719469] 'agreement among raft nodes before linearized reading' (duration: 172.377278ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:51:15.128728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.15142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-24T15:51:15.128883Z","caller":"traceutil/trace.go:171","msg":"trace[1888392447] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4613; }","duration":"163.295483ms","start":"2026-08-24T15:51:14.965518Z","end":"2026-08-24T15:51:15.128813Z","steps":["trace[1888392447] 'agreement among raft nodes before linearized reading' (duration: 163.062806ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:51:15.12908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.121974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6lt4p\" ","response":"range_response_count:1 size:6292"} {"level":"info","ts":"2026-08-24T15:51:15.129123Z","caller":"traceutil/trace.go:171","msg":"trace[1391743315] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6lt4p; range_end:; response_count:1; response_revision:4613; }","duration":"146.189146ms","start":"2026-08-24T15:51:14.982922Z","end":"2026-08-24T15:51:15.129111Z","steps":["trace[1391743315] 'agreement among raft nodes before linearized reading' (duration: 146.087554ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:51:15.128778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.957131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-qbnk9\" ","response":"range_response_count:1 size:20232"} {"level":"info","ts":"2026-08-24T15:51:15.129224Z","caller":"traceutil/trace.go:171","msg":"trace[1255693596] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-qbnk9; range_end:; response_count:1; response_revision:4613; }","duration":"140.393821ms","start":"2026-08-24T15:51:14.988787Z","end":"2026-08-24T15:51:15.129181Z","steps":["trace[1255693596] 'agreement among raft nodes before linearized reading' (duration: 139.888239ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:51:26.052706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.943066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:51:26.052888Z","caller":"traceutil/trace.go:171","msg":"trace[1442255100] linearizableReadLoop","detail":"{readStateIndex:4883; appliedIndex:4882; }","duration":"138.925514ms","start":"2026-08-24T15:51:25.913938Z","end":"2026-08-24T15:51:26.052864Z","steps":["trace[1442255100] 'read index received' (duration: 1.62431ms)","trace[1442255100] 'applied index is now lower than readState.Index' (duration: 137.299244ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:51:26.053003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.072658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:51:26.053039Z","caller":"traceutil/trace.go:171","msg":"trace[2045917081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4676; }","duration":"139.13534ms","start":"2026-08-24T15:51:25.913889Z","end":"2026-08-24T15:51:26.053024Z","steps":["trace[2045917081] 'agreement among raft nodes before linearized reading' (duration: 139.042968ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:51:26.053015Z","caller":"traceutil/trace.go:171","msg":"trace[1754199152] transaction","detail":"{read_only:false; response_revision:4676; number_of_response:1; }","duration":"208.858608ms","start":"2026-08-24T15:51:25.844123Z","end":"2026-08-24T15:51:26.052981Z","steps":["trace[1754199152] 'process raft request' (duration: 71.449281ms)","trace[1754199152] 'compare' (duration: 136.559807ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:51:32.80726Z","caller":"traceutil/trace.go:171","msg":"trace[1599899157] transaction","detail":"{read_only:false; response_revision:4710; number_of_response:1; }","duration":"223.73842ms","start":"2026-08-24T15:51:32.583497Z","end":"2026-08-24T15:51:32.807235Z","steps":["trace[1599899157] 'process raft request' (duration: 223.586066ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:52:46.271564Z","caller":"traceutil/trace.go:171","msg":"trace[7275888] linearizableReadLoop","detail":"{readStateIndex:5383; appliedIndex:5382; }","duration":"110.205442ms","start":"2026-08-24T15:52:46.161333Z","end":"2026-08-24T15:52:46.271539Z","steps":["trace[7275888] 'read index received' (duration: 110.030886ms)","trace[7275888] 'applied index is now lower than readState.Index' (duration: 173.416µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:52:46.271618Z","caller":"traceutil/trace.go:171","msg":"trace[1830155178] transaction","detail":"{read_only:false; response_revision:5158; number_of_response:1; }","duration":"112.852119ms","start":"2026-08-24T15:52:46.15873Z","end":"2026-08-24T15:52:46.271583Z","steps":["trace[1830155178] 'process raft request' (duration: 112.657453ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:52:46.271765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.419407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-24T15:52:46.271816Z","caller":"traceutil/trace.go:171","msg":"trace[1692012576] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5158; }","duration":"110.479028ms","start":"2026-08-24T15:52:46.161318Z","end":"2026-08-24T15:52:46.271797Z","steps":["trace[1692012576] 'agreement among raft nodes before linearized reading' (duration: 110.324074ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:55:16.152692Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3794} {"level":"info","ts":"2026-08-24T15:55:16.194471Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3794,"took":"40.674521ms","hash":3173539245,"current-db-size-bytes":36286464,"current-db-size":"36 MB","current-db-size-in-use-bytes":15388672,"current-db-size-in-use":"15 MB"} {"level":"info","ts":"2026-08-24T15:55:16.194537Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3173539245,"revision":3794,"compact-revision":2430} {"level":"info","ts":"2026-08-24T16:00:16.159562Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5922} {"level":"info","ts":"2026-08-24T16:00:16.215362Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5922,"took":"54.862499ms","hash":1751274074,"current-db-size-bytes":36286464,"current-db-size":"36 MB","current-db-size-in-use-bytes":10780672,"current-db-size-in-use":"11 MB"} {"level":"info","ts":"2026-08-24T16:00:16.215439Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1751274074,"revision":5922,"compact-revision":3794} {"level":"info","ts":"2026-08-24T16:02:06.096948Z","caller":"traceutil/trace.go:171","msg":"trace[24165728] transaction","detail":"{read_only:false; response_revision:7993; number_of_response:1; }","duration":"134.002872ms","start":"2026-08-24T16:02:05.962889Z","end":"2026-08-24T16:02:06.096892Z","steps":["trace[24165728] 'process raft request' (duration: 128.791781ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:02:06.307395Z","caller":"traceutil/trace.go:171","msg":"trace[2085898605] transaction","detail":"{read_only:false; response_revision:7995; number_of_response:1; }","duration":"188.34014ms","start":"2026-08-24T16:02:06.118996Z","end":"2026-08-24T16:02:06.307336Z","steps":["trace[2085898605] 'process raft request' (duration: 157.078568ms)","trace[2085898605] 'compare' (duration: 30.925054ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:02:06.74483Z","caller":"traceutil/trace.go:171","msg":"trace[1590696566] transaction","detail":"{read_only:false; response_revision:7996; number_of_response:1; }","duration":"137.678883ms","start":"2026-08-24T16:02:06.607126Z","end":"2026-08-24T16:02:06.744805Z","steps":["trace[1590696566] 'process raft request' (duration: 137.52545ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:02:06.93088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.544505ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T16:02:06.930989Z","caller":"traceutil/trace.go:171","msg":"trace[2073730919] linearizableReadLoop","detail":"{readStateIndex:8336; appliedIndex:8334; }","duration":"199.641063ms","start":"2026-08-24T16:02:06.731331Z","end":"2026-08-24T16:02:06.930972Z","steps":["trace[2073730919] 'read index received' (duration: 13.371325ms)","trace[2073730919] 'applied index is now lower than readState.Index' (duration: 186.268228ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:02:06.93106Z","caller":"traceutil/trace.go:171","msg":"trace[1593709897] transaction","detail":"{read_only:false; response_revision:7997; number_of_response:1; }","duration":"322.819673ms","start":"2026-08-24T16:02:06.608184Z","end":"2026-08-24T16:02:06.931003Z","steps":["trace[1593709897] 'process raft request' (duration: 182.046172ms)","trace[1593709897] 'compare' (duration: 140.299859ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:02:06.931246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:02:06.608165Z","time spent":"322.983227ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:02:06.931114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.842077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T16:02:06.931365Z","caller":"traceutil/trace.go:171","msg":"trace[379539101] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:7997; }","duration":"200.119636ms","start":"2026-08-24T16:02:06.731211Z","end":"2026-08-24T16:02:06.93133Z","steps":["trace[379539101] 'agreement among raft nodes before linearized reading' (duration: 199.858888ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:02:07.307532Z","caller":"traceutil/trace.go:171","msg":"trace[2001810167] transaction","detail":"{read_only:false; response_revision:8000; number_of_response:1; }","duration":"109.403267ms","start":"2026-08-24T16:02:07.198102Z","end":"2026-08-24T16:02:07.307505Z","steps":["trace[2001810167] 'process raft request' (duration: 109.263583ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:02:13.321557Z","caller":"traceutil/trace.go:171","msg":"trace[1565192776] linearizableReadLoop","detail":"{readStateIndex:8364; appliedIndex:8363; }","duration":"365.950133ms","start":"2026-08-24T16:02:12.955584Z","end":"2026-08-24T16:02:13.321534Z","steps":["trace[1565192776] 'read index received' (duration: 365.691316ms)","trace[1565192776] 'applied index is now lower than readState.Index' (duration: 257.497µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:02:13.321647Z","caller":"traceutil/trace.go:171","msg":"trace[857201994] transaction","detail":"{read_only:false; response_revision:8024; number_of_response:1; }","duration":"544.947389ms","start":"2026-08-24T16:02:12.776668Z","end":"2026-08-24T16:02:13.321615Z","steps":["trace[857201994] 'process raft request' (duration: 544.714813ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:02:13.321773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.177878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-24T16:02:13.321829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:02:12.776649Z","time spent":"545.097483ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:02:13.321861Z","caller":"traceutil/trace.go:171","msg":"trace[1861561337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8024; }","duration":"366.327962ms","start":"2026-08-24T16:02:12.955522Z","end":"2026-08-24T16:02:13.32185Z","steps":["trace[1861561337] 'agreement among raft nodes before linearized reading' (duration: 366.138107ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:02:13.32193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:02:12.955495Z","time spent":"366.403764ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T16:02:13.322092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.948437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6lt4p\" ","response":"range_response_count:1 size:6292"} {"level":"info","ts":"2026-08-24T16:02:13.322142Z","caller":"traceutil/trace.go:171","msg":"trace[1225313436] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6lt4p; range_end:; response_count:1; response_revision:8024; }","duration":"357.0348ms","start":"2026-08-24T16:02:12.965096Z","end":"2026-08-24T16:02:13.322131Z","steps":["trace[1225313436] 'agreement among raft nodes before linearized reading' (duration: 356.865405ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:02:13.322178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:02:12.965078Z","time spent":"357.092112ms","remote":"127.0.0.1:43484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6316,"request content":"key:\"/registry/pods/openstack/keepalived-6lt4p\" "} {"level":"warn","ts":"2026-08-24T16:02:13.322185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.526196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-24T16:02:13.322363Z","caller":"traceutil/trace.go:171","msg":"trace[1259120236] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8024; }","duration":"360.834145ms","start":"2026-08-24T16:02:12.961499Z","end":"2026-08-24T16:02:13.322333Z","steps":["trace[1259120236] 'agreement among raft nodes before linearized reading' (duration: 360.357213ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:02:13.322429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:02:12.961453Z","time spent":"360.964318ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-24T16:02:13.453059Z","caller":"traceutil/trace.go:171","msg":"trace[962265563] transaction","detail":"{read_only:false; response_revision:8026; number_of_response:1; }","duration":"124.798902ms","start":"2026-08-24T16:02:13.328227Z","end":"2026-08-24T16:02:13.453026Z","steps":["trace[962265563] 'process raft request' (duration: 118.509703ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:02:13.453443Z","caller":"traceutil/trace.go:171","msg":"trace[1772612335] transaction","detail":"{read_only:false; response_revision:8027; number_of_response:1; }","duration":"124.73894ms","start":"2026-08-24T16:02:13.32863Z","end":"2026-08-24T16:02:13.453369Z","steps":["trace[1772612335] 'process raft request' (duration: 124.34451ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:02:13.453811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.518514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-24T16:02:13.45388Z","caller":"traceutil/trace.go:171","msg":"trace[1803734475] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8027; }","duration":"122.660458ms","start":"2026-08-24T16:02:13.331203Z","end":"2026-08-24T16:02:13.453863Z","steps":["trace[1803734475] 'agreement among raft nodes before linearized reading' (duration: 122.261098ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:02:13.453836Z","caller":"traceutil/trace.go:171","msg":"trace[1221658915] linearizableReadLoop","detail":"{readStateIndex:8367; appliedIndex:8364; }","duration":"121.915029ms","start":"2026-08-24T16:02:13.331336Z","end":"2026-08-24T16:02:13.453251Z","steps":["trace[1221658915] 'read index received' (duration: 9.874217ms)","trace[1221658915] 'applied index is now lower than readState.Index' (duration: 112.037802ms)"],"step_count":2}