{"level":"warn","ts":"2026-02-10T18:50:24.731876Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-10T18:50:24.731967Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.12: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://162.253.55.12:2380","--initial-cluster=instance=https://162.253.55.12:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.12:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.12:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-10T18:50:24.732226Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-10T18:50:24.732243Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.12:2380"]} {"level":"info","ts":"2026-02-10T18:50:24.732329Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-10T18:50:24.733045Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"]} {"level":"info","ts":"2026-02-10T18:50:24.733184Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.12:2380"],"listen-peer-urls":["https://162.253.55.12:2380"],"advertise-client-urls":["https://162.253.55.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.12:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-10T18:50:24.738306Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.870747ms"} {"level":"info","ts":"2026-02-10T18:50:24.770142Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b4c6dc24a78438ca","cluster-id":"587227f9a3f73e98"} {"level":"info","ts":"2026-02-10T18:50:24.770305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=()"} {"level":"info","ts":"2026-02-10T18:50:24.77039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became follower at term 0"} {"level":"info","ts":"2026-02-10T18:50:24.770405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b4c6dc24a78438ca [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-10T18:50:24.770452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became follower at term 1"} {"level":"info","ts":"2026-02-10T18:50:24.77053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=(13026341022203132106)"} {"level":"warn","ts":"2026-02-10T18:50:24.778476Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-10T18:50:24.779826Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-10T18:50:24.785073Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-10T18:50:24.788754Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b4c6dc24a78438ca","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-10T18:50:24.80202Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-10T18:50:24.802269Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-10T18:50:24.802307Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-10T18:50:24.80286Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-10T18:50:24.802987Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b4c6dc24a78438ca","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-10T18:50:24.803337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=(13026341022203132106)"} {"level":"info","ts":"2026-02-10T18:50:24.806327Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"587227f9a3f73e98","local-member-id":"b4c6dc24a78438ca","added-peer-id":"b4c6dc24a78438ca","added-peer-peer-urls":["https://162.253.55.12:2380"]} {"level":"info","ts":"2026-02-10T18:50:24.806696Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b4c6dc24a78438ca","initial-advertise-peer-urls":["https://162.253.55.12:2380"],"listen-peer-urls":["https://162.253.55.12:2380"],"advertise-client-urls":["https://162.253.55.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-10T18:50:24.806721Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-10T18:50:24.808028Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.12:2380"} {"level":"info","ts":"2026-02-10T18:50:24.808687Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.12:2380"} {"level":"info","ts":"2026-02-10T18:50:25.271611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca is starting a new election at term 1"} {"level":"info","ts":"2026-02-10T18:50:25.271738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became pre-candidate at term 1"} {"level":"info","ts":"2026-02-10T18:50:25.271781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca received MsgPreVoteResp from b4c6dc24a78438ca at term 1"} {"level":"info","ts":"2026-02-10T18:50:25.271812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became candidate at term 2"} {"level":"info","ts":"2026-02-10T18:50:25.271841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca received MsgVoteResp from b4c6dc24a78438ca at term 2"} {"level":"info","ts":"2026-02-10T18:50:25.271863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became leader at term 2"} {"level":"info","ts":"2026-02-10T18:50:25.271886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b4c6dc24a78438ca elected leader b4c6dc24a78438ca at term 2"} {"level":"info","ts":"2026-02-10T18:50:25.274433Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b4c6dc24a78438ca","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.12:2379]}","request-path":"/0/members/b4c6dc24a78438ca/attributes","cluster-id":"587227f9a3f73e98","publish-timeout":"7s"} {"level":"info","ts":"2026-02-10T18:50:25.27451Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T18:50:25.274919Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-10T18:50:25.274967Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-10T18:50:25.275084Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:50:25.274808Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T18:50:25.277027Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"587227f9a3f73e98","local-member-id":"b4c6dc24a78438ca","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:50:25.277102Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:50:25.27713Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:50:25.278644Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.12:2379"} {"level":"info","ts":"2026-02-10T18:50:25.284896Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-10T18:50:28.83272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.742444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:50:28.832872Z","caller":"traceutil/trace.go:171","msg":"trace[1248850796] transaction","detail":"{read_only:false; response_revision:154; number_of_response:1; }","duration":"172.7823ms","start":"2026-02-10T18:50:28.660073Z","end":"2026-02-10T18:50:28.832855Z","steps":["trace[1248850796] 'process raft request' (duration: 55.162855ms)","trace[1248850796] 'compare' (duration: 116.550249ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:50:29.340117Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255048295547770,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:50:29.840532Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255048295547770,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:50:29.96529Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.152880001s","expected-duration":"1s"} {"level":"info","ts":"2026-02-10T18:50:29.965995Z","caller":"traceutil/trace.go:171","msg":"trace[1672907302] transaction","detail":"{read_only:false; response_revision:155; number_of_response:1; }","duration":"1.153479656s","start":"2026-02-10T18:50:28.812276Z","end":"2026-02-10T18:50:29.965755Z","steps":["trace[1672907302] 'process raft request' (duration: 1.15324109s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:29.966104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:28.812247Z","time spent":"1.153790592s","remote":"127.0.0.1:33624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5855,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T18:50:30.426239Z","caller":"traceutil/trace.go:171","msg":"trace[2108071983] linearizableReadLoop","detail":"{readStateIndex:160; appliedIndex:158; }","duration":"1.58715555s","start":"2026-02-10T18:50:28.83906Z","end":"2026-02-10T18:50:30.426215Z","steps":["trace[2108071983] 'read index received' (duration: 1.126466316s)","trace[2108071983] 'applied index is now lower than readState.Index' (duration: 460.688274ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:50:30.426493Z","caller":"traceutil/trace.go:171","msg":"trace[901839813] transaction","detail":"{read_only:false; response_revision:156; number_of_response:1; }","duration":"1.52141959s","start":"2026-02-10T18:50:28.905062Z","end":"2026-02-10T18:50:30.426482Z","steps":["trace[901839813] 'process raft request' (duration: 1.514991406s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:30.426568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:28.905038Z","time spent":"1.521481982s","remote":"127.0.0.1:33624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3937,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T18:50:30.426767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.587698163s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:cronjob-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:50:30.427533Z","caller":"traceutil/trace.go:171","msg":"trace[246835939] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:cronjob-controller; range_end:; response_count:0; response_revision:156; }","duration":"1.588487192s","start":"2026-02-10T18:50:28.839027Z","end":"2026-02-10T18:50:30.427514Z","steps":["trace[246835939] 'agreement among raft nodes before linearized reading' (duration: 1.587683113s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:30.427611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:28.839012Z","time spent":"1.588585054s","remote":"127.0.0.1:33802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/system:controller:cronjob-controller\" "} {"level":"warn","ts":"2026-02-10T18:50:30.427006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.002395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:50:30.427754Z","caller":"traceutil/trace.go:171","msg":"trace[329272099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:156; }","duration":"992.804004ms","start":"2026-02-10T18:50:29.43494Z","end":"2026-02-10T18:50:30.427744Z","steps":["trace[329272099] 'agreement among raft nodes before linearized reading' (duration: 991.984394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:30.427784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:29.434924Z","time spent":"992.852235ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:50:30.427076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.184627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:50:30.427917Z","caller":"traceutil/trace.go:171","msg":"trace[1514731769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:156; }","duration":"856.053579ms","start":"2026-02-10T18:50:29.571855Z","end":"2026-02-10T18:50:30.427909Z","steps":["trace[1514731769] 'agreement among raft nodes before linearized reading' (duration: 855.206728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:30.427945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:29.571838Z","time spent":"856.099909ms","remote":"127.0.0.1:33462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:50:30.427115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.129775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-10T18:50:30.429394Z","caller":"traceutil/trace.go:171","msg":"trace[1210756906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:156; }","duration":"903.470418ms","start":"2026-02-10T18:50:29.524945Z","end":"2026-02-10T18:50:30.428416Z","steps":["trace[1210756906] 'agreement among raft nodes before linearized reading' (duration: 902.142206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:30.42943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:29.524926Z","time spent":"904.493523ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T18:50:30.427136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.251383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-10T18:50:30.429637Z","caller":"traceutil/trace.go:171","msg":"trace[13015870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:156; }","duration":"905.773393ms","start":"2026-02-10T18:50:29.523853Z","end":"2026-02-10T18:50:30.429627Z","steps":["trace[13015870] 'agreement among raft nodes before linearized reading' (duration: 903.269673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:30.429663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:29.523838Z","time spent":"905.816505ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-10T18:50:31.562285Z","caller":"traceutil/trace.go:171","msg":"trace[253081127] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"118.751773ms","start":"2026-02-10T18:50:31.443504Z","end":"2026-02-10T18:50:31.562255Z","steps":["trace[253081127] 'process raft request' (duration: 23.107025ms)","trace[253081127] 'compare' (duration: 95.386012ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:50:31.56265Z","caller":"traceutil/trace.go:171","msg":"trace[28169797] linearizableReadLoop","detail":"{readStateIndex:265; appliedIndex:264; }","duration":"101.13027ms","start":"2026-02-10T18:50:31.461469Z","end":"2026-02-10T18:50:31.5626Z","steps":["trace[28169797] 'read index received' (duration: 5.101293ms)","trace[28169797] 'applied index is now lower than readState.Index' (duration: 96.027667ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:50:31.56289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.370986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3179"} {"level":"info","ts":"2026-02-10T18:50:31.562992Z","caller":"traceutil/trace.go:171","msg":"trace[392767530] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:260; }","duration":"101.513109ms","start":"2026-02-10T18:50:31.461467Z","end":"2026-02-10T18:50:31.56298Z","steps":["trace[392767530] 'agreement among raft nodes before linearized reading' (duration: 101.278194ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:35.518879Z","caller":"traceutil/trace.go:171","msg":"trace[824377447] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"121.809976ms","start":"2026-02-10T18:50:35.397006Z","end":"2026-02-10T18:50:35.518816Z","steps":["trace[824377447] 'process raft request' (duration: 49.688974ms)","trace[824377447] 'compare' (duration: 71.888066ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:50:35.815148Z","caller":"traceutil/trace.go:171","msg":"trace[72751574] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"119.353927ms","start":"2026-02-10T18:50:35.695771Z","end":"2026-02-10T18:50:35.815125Z","steps":["trace[72751574] 'process raft request' (duration: 73.516297ms)","trace[72751574] 'compare' (duration: 45.683216ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:50:35.969616Z","caller":"traceutil/trace.go:171","msg":"trace[1810255187] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"116.876917ms","start":"2026-02-10T18:50:35.852714Z","end":"2026-02-10T18:50:35.969591Z","steps":["trace[1810255187] 'process raft request' (duration: 76.714363ms)","trace[1810255187] 'compare' (duration: 39.655942ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:50:35.969949Z","caller":"traceutil/trace.go:171","msg":"trace[464164941] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"112.229575ms","start":"2026-02-10T18:50:35.857698Z","end":"2026-02-10T18:50:35.969928Z","steps":["trace[464164941] 'process raft request' (duration: 111.611231ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:36.402793Z","caller":"traceutil/trace.go:171","msg":"trace[1198906237] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"133.203658ms","start":"2026-02-10T18:50:36.269569Z","end":"2026-02-10T18:50:36.402772Z","steps":["trace[1198906237] 'process raft request' (duration: 97.394779ms)","trace[1198906237] 'compare' (duration: 35.40351ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:50:36.403076Z","caller":"traceutil/trace.go:171","msg":"trace[1391819389] linearizableReadLoop","detail":"{readStateIndex:401; appliedIndex:397; }","duration":"121.440517ms","start":"2026-02-10T18:50:36.281627Z","end":"2026-02-10T18:50:36.403067Z","steps":["trace[1391819389] 'read index received' (duration: 29.087309ms)","trace[1391819389] 'applied index is now lower than readState.Index' (duration: 92.352648ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:50:36.403147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.505218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-02-10T18:50:36.40393Z","caller":"traceutil/trace.go:171","msg":"trace[2075329528] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:388; }","duration":"122.295257ms","start":"2026-02-10T18:50:36.281594Z","end":"2026-02-10T18:50:36.403889Z","steps":["trace[2075329528] 'agreement among raft nodes before linearized reading' (duration: 121.501638ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:36.403168Z","caller":"traceutil/trace.go:171","msg":"trace[795403851] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"133.406134ms","start":"2026-02-10T18:50:36.269757Z","end":"2026-02-10T18:50:36.403163Z","steps":["trace[795403851] 'process raft request' (duration: 132.926222ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:36.403251Z","caller":"traceutil/trace.go:171","msg":"trace[1615734055] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"132.957203ms","start":"2026-02-10T18:50:36.270288Z","end":"2026-02-10T18:50:36.403245Z","steps":["trace[1615734055] 'process raft request' (duration: 132.42468ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:36.61175Z","caller":"traceutil/trace.go:171","msg":"trace[1236064894] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"104.497779ms","start":"2026-02-10T18:50:36.507232Z","end":"2026-02-10T18:50:36.61173Z","steps":["trace[1236064894] 'process raft request' (duration: 103.928126ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:36.613236Z","caller":"traceutil/trace.go:171","msg":"trace[752169101] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"104.333536ms","start":"2026-02-10T18:50:36.508883Z","end":"2026-02-10T18:50:36.613216Z","steps":["trace[752169101] 'process raft request' (duration: 102.394069ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:41.885685Z","caller":"traceutil/trace.go:171","msg":"trace[219353217] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"212.117045ms","start":"2026-02-10T18:50:41.673545Z","end":"2026-02-10T18:50:41.885662Z","steps":["trace[219353217] 'process raft request' (duration: 145.837683ms)","trace[219353217] 'compare' (duration: 64.830787ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:50:44.445559Z","caller":"traceutil/trace.go:171","msg":"trace[583930617] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"175.2547ms","start":"2026-02-10T18:50:44.270288Z","end":"2026-02-10T18:50:44.445543Z","steps":["trace[583930617] 'process raft request' (duration: 175.145647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:44.749091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.732344ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:50:44.749239Z","caller":"traceutil/trace.go:171","msg":"trace[588299405] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"298.909939ms","start":"2026-02-10T18:50:44.450304Z","end":"2026-02-10T18:50:44.749214Z","steps":["trace[588299405] 'process raft request' (duration: 178.127909ms)","trace[588299405] 'compare' (duration: 116.439376ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:50:44.921016Z","caller":"traceutil/trace.go:171","msg":"trace[368033112] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"163.359273ms","start":"2026-02-10T18:50:44.75764Z","end":"2026-02-10T18:50:44.920999Z","steps":["trace[368033112] 'process raft request' (duration: 95.694668ms)","trace[368033112] 'compare' (duration: 67.597244ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:50:44.928112Z","caller":"traceutil/trace.go:171","msg":"trace[2035156971] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"169.402259ms","start":"2026-02-10T18:50:44.758696Z","end":"2026-02-10T18:50:44.928098Z","steps":["trace[2035156971] 'process raft request' (duration: 169.244735ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:44.928757Z","caller":"traceutil/trace.go:171","msg":"trace[1156321148] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"170.026004ms","start":"2026-02-10T18:50:44.758723Z","end":"2026-02-10T18:50:44.928749Z","steps":["trace[1156321148] 'process raft request' (duration: 169.334957ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:44.929059Z","caller":"traceutil/trace.go:171","msg":"trace[686038510] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"168.272082ms","start":"2026-02-10T18:50:44.760778Z","end":"2026-02-10T18:50:44.92905Z","steps":["trace[686038510] 'process raft request' (duration: 167.738029ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:44.929438Z","caller":"traceutil/trace.go:171","msg":"trace[1879360217] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"168.580519ms","start":"2026-02-10T18:50:44.760844Z","end":"2026-02-10T18:50:44.929424Z","steps":["trace[1879360217] 'process raft request' (duration: 167.78739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:45.184082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.812008ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:50:45.184404Z","caller":"traceutil/trace.go:171","msg":"trace[122705217] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:446; }","duration":"161.442678ms","start":"2026-02-10T18:50:45.022944Z","end":"2026-02-10T18:50:45.184387Z","steps":["trace[122705217] 'read index received' (duration: 51.134428ms)","trace[122705217] 'applied index is now lower than readState.Index' (duration: 110.30726ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:50:45.18448Z","caller":"traceutil/trace.go:171","msg":"trace[580655165] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"247.221378ms","start":"2026-02-10T18:50:44.937248Z","end":"2026-02-10T18:50:45.18447Z","steps":["trace[580655165] 'process raft request' (duration: 136.821956ms)","trace[580655165] 'compare' (duration: 109.696015ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:50:45.184681Z","caller":"traceutil/trace.go:171","msg":"trace[186731192] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"241.883529ms","start":"2026-02-10T18:50:44.942738Z","end":"2026-02-10T18:50:45.184621Z","steps":["trace[186731192] 'process raft request' (duration: 241.558992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:45.184895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.94457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-mc7hs\" ","response":"range_response_count:1 size:3994"} {"level":"info","ts":"2026-02-10T18:50:45.184933Z","caller":"traceutil/trace.go:171","msg":"trace[771237849] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-mc7hs; range_end:; response_count:1; response_revision:436; }","duration":"162.010012ms","start":"2026-02-10T18:50:45.022913Z","end":"2026-02-10T18:50:45.184923Z","steps":["trace[771237849] 'agreement among raft nodes before linearized reading' (duration: 161.94897ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:45.185212Z","caller":"traceutil/trace.go:171","msg":"trace[936424008] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"247.793442ms","start":"2026-02-10T18:50:44.937402Z","end":"2026-02-10T18:50:45.185196Z","steps":["trace[936424008] 'process raft request' (duration: 246.770748ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:45.185809Z","caller":"traceutil/trace.go:171","msg":"trace[770105780] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"246.490551ms","start":"2026-02-10T18:50:44.939305Z","end":"2026-02-10T18:50:45.185795Z","steps":["trace[770105780] 'process raft request' (duration: 244.944664ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:45.184841Z","caller":"traceutil/trace.go:171","msg":"trace[1082639427] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"241.620684ms","start":"2026-02-10T18:50:44.94321Z","end":"2026-02-10T18:50:45.18483Z","steps":["trace[1082639427] 'process raft request' (duration: 241.133652ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:45.198904Z","caller":"traceutil/trace.go:171","msg":"trace[1012150346] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"174.44763ms","start":"2026-02-10T18:50:45.024427Z","end":"2026-02-10T18:50:45.198875Z","steps":["trace[1012150346] 'process raft request' (duration: 174.405489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:45.199187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.128244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4173"} {"level":"info","ts":"2026-02-10T18:50:45.199224Z","caller":"traceutil/trace.go:171","msg":"trace[544192900] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:437; }","duration":"172.182896ms","start":"2026-02-10T18:50:45.027034Z","end":"2026-02-10T18:50:45.199216Z","steps":["trace[544192900] 'agreement among raft nodes before linearized reading' (duration: 172.109184ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:45.377256Z","caller":"traceutil/trace.go:171","msg":"trace[563882832] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"182.652495ms","start":"2026-02-10T18:50:45.194578Z","end":"2026-02-10T18:50:45.37723Z","steps":["trace[563882832] 'process raft request' (duration: 182.616575ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:45.377548Z","caller":"traceutil/trace.go:171","msg":"trace[1492289580] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"183.187119ms","start":"2026-02-10T18:50:45.194355Z","end":"2026-02-10T18:50:45.377542Z","steps":["trace[1492289580] 'process raft request' (duration: 90.565544ms)","trace[1492289580] 'compare' (duration: 92.192495ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:50:45.405875Z","caller":"traceutil/trace.go:171","msg":"trace[422312384] transaction","detail":"{read_only:false; number_of_response:1; response_revision:439; }","duration":"201.693413ms","start":"2026-02-10T18:50:45.204165Z","end":"2026-02-10T18:50:45.405859Z","steps":["trace[422312384] 'process raft request' (duration: 201.57169ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:47.155712Z","caller":"traceutil/trace.go:171","msg":"trace[1536038752] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"149.710056ms","start":"2026-02-10T18:50:47.00598Z","end":"2026-02-10T18:50:47.15569Z","steps":["trace[1536038752] 'process raft request' (duration: 145.31712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:51.470561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.07684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-10T18:50:51.470639Z","caller":"traceutil/trace.go:171","msg":"trace[363674571] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:468; }","duration":"226.192953ms","start":"2026-02-10T18:50:51.244428Z","end":"2026-02-10T18:50:51.47062Z","steps":["trace[363674571] 'range keys from in-memory index tree' (duration: 225.996338ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:55.803901Z","caller":"traceutil/trace.go:171","msg":"trace[1756238925] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"151.833657ms","start":"2026-02-10T18:50:55.650198Z","end":"2026-02-10T18:50:55.802031Z","steps":["trace[1756238925] 'process raft request' (duration: 151.510289ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:57.476877Z","caller":"traceutil/trace.go:171","msg":"trace[1137934453] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"378.385058ms","start":"2026-02-10T18:50:57.098469Z","end":"2026-02-10T18:50:57.476854Z","steps":["trace[1137934453] 'process raft request' (duration: 378.173853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:57.477115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:57.098457Z","time spent":"378.557393ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:50:57.477557Z","caller":"traceutil/trace.go:171","msg":"trace[1519540379] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:533; }","duration":"289.175745ms","start":"2026-02-10T18:50:57.188368Z","end":"2026-02-10T18:50:57.477544Z","steps":["trace[1519540379] 'read index received' (duration: 289.171175ms)","trace[1519540379] 'applied index is now lower than readState.Index' (duration: 3.75µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:50:57.477653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.277828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-10T18:50:57.477716Z","caller":"traceutil/trace.go:171","msg":"trace[639143383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:513; }","duration":"289.34702ms","start":"2026-02-10T18:50:57.188334Z","end":"2026-02-10T18:50:57.477681Z","steps":["trace[639143383] 'agreement among raft nodes before linearized reading' (duration: 289.261308ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:57.482184Z","caller":"traceutil/trace.go:171","msg":"trace[1332979919] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"226.50826ms","start":"2026-02-10T18:50:57.255641Z","end":"2026-02-10T18:50:57.482149Z","steps":["trace[1332979919] 'process raft request' (duration: 226.408218ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:51:01.156133Z","caller":"traceutil/trace.go:171","msg":"trace[1896249552] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:545; }","duration":"118.20915ms","start":"2026-02-10T18:51:01.037895Z","end":"2026-02-10T18:51:01.156104Z","steps":["trace[1896249552] 'read index received' (duration: 84.357707ms)","trace[1896249552] 'applied index is now lower than readState.Index' (duration: 33.850233ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:51:01.156195Z","caller":"traceutil/trace.go:171","msg":"trace[146961658] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"272.802932ms","start":"2026-02-10T18:51:00.883375Z","end":"2026-02-10T18:51:01.156178Z","steps":["trace[146961658] 'process raft request' (duration: 239.001061ms)","trace[146961658] 'compare' (duration: 33.560176ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:51:01.156285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.366013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:51:01.156325Z","caller":"traceutil/trace.go:171","msg":"trace[711052652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"118.456926ms","start":"2026-02-10T18:51:01.037856Z","end":"2026-02-10T18:51:01.156313Z","steps":["trace[711052652] 'agreement among raft nodes before linearized reading' (duration: 118.349033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:07.0963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.082601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-10T18:51:07.096394Z","caller":"traceutil/trace.go:171","msg":"trace[2131770074] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:540; }","duration":"678.194464ms","start":"2026-02-10T18:51:06.418171Z","end":"2026-02-10T18:51:07.096366Z","steps":["trace[2131770074] 'range keys from in-memory index tree' (duration: 677.969378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:07.096433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:06.418157Z","time spent":"678.268476ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-10T18:51:07.29608Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255048295548576,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:51:07.656508Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.050550182s","expected-duration":"1s"} {"level":"info","ts":"2026-02-10T18:51:07.656936Z","caller":"traceutil/trace.go:171","msg":"trace[1189406803] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:562; }","duration":"861.977256ms","start":"2026-02-10T18:51:06.794934Z","end":"2026-02-10T18:51:07.656912Z","steps":["trace[1189406803] 'read index received' (duration: 861.745121ms)","trace[1189406803] 'applied index is now lower than readState.Index' (duration: 231.035µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:51:07.657014Z","caller":"traceutil/trace.go:171","msg":"trace[436585654] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.051120087s","start":"2026-02-10T18:51:06.605885Z","end":"2026-02-10T18:51:07.657005Z","steps":["trace[436585654] 'process raft request' (duration: 1.050872911s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:07.657116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:06.605869Z","time spent":"1.051173378s","remote":"127.0.0.1:33508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T18:51:07.657457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.518659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zt5j9\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-10T18:51:07.657493Z","caller":"traceutil/trace.go:171","msg":"trace[790664020] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zt5j9; range_end:; response_count:1; response_revision:541; }","duration":"862.584601ms","start":"2026-02-10T18:51:06.794896Z","end":"2026-02-10T18:51:07.65748Z","steps":["trace[790664020] 'agreement among raft nodes before linearized reading' (duration: 862.475448ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:07.657524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:06.794867Z","time spent":"862.650623ms","remote":"127.0.0.1:33624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16675,"request content":"key:\"/registry/pods/kube-system/cilium-zt5j9\" "} {"level":"warn","ts":"2026-02-10T18:51:07.65768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.608805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:51:07.657705Z","caller":"traceutil/trace.go:171","msg":"trace[1112007857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:541; }","duration":"621.658096ms","start":"2026-02-10T18:51:07.036039Z","end":"2026-02-10T18:51:07.657697Z","steps":["trace[1112007857] 'agreement among raft nodes before linearized reading' (duration: 621.620215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:07.657727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:07.036023Z","time spent":"621.699747ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:51:07.65807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.037301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6534"} {"level":"info","ts":"2026-02-10T18:51:07.658174Z","caller":"traceutil/trace.go:171","msg":"trace[1341288979] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:541; }","duration":"557.178725ms","start":"2026-02-10T18:51:07.100986Z","end":"2026-02-10T18:51:07.658165Z","steps":["trace[1341288979] 'agreement among raft nodes before linearized reading' (duration: 557.033001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:07.658201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:07.100972Z","time spent":"557.223195ms","remote":"127.0.0.1:33540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":6557,"request content":"key:\"/registry/configmaps/kube-system/cilium-config\" "} {"level":"info","ts":"2026-02-10T18:51:08.133374Z","caller":"traceutil/trace.go:171","msg":"trace[19798664] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"465.620927ms","start":"2026-02-10T18:51:07.667728Z","end":"2026-02-10T18:51:08.133349Z","steps":["trace[19798664] 'process raft request' (duration: 465.480903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:08.133514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:07.66771Z","time spent":"465.731299ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:51:08.454745Z","caller":"traceutil/trace.go:171","msg":"trace[1220600909] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:563; }","duration":"775.121802ms","start":"2026-02-10T18:51:07.6796Z","end":"2026-02-10T18:51:08.454721Z","steps":["trace[1220600909] 'read index received' (duration: 453.593515ms)","trace[1220600909] 'applied index is now lower than readState.Index' (duration: 321.527527ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:51:08.455072Z","caller":"traceutil/trace.go:171","msg":"trace[2021299697] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"779.936519ms","start":"2026-02-10T18:51:07.675126Z","end":"2026-02-10T18:51:08.455063Z","steps":["trace[2021299697] 'process raft request' (duration: 779.490298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:08.455169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.549183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-10T18:51:08.455539Z","caller":"traceutil/trace.go:171","msg":"trace[1121748863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:543; }","duration":"775.953463ms","start":"2026-02-10T18:51:07.679577Z","end":"2026-02-10T18:51:08.45553Z","steps":["trace[1121748863] 'agreement among raft nodes before linearized reading' (duration: 775.541153ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:08.455582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:07.67957Z","time spent":"776.003404ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T18:51:08.455894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:07.675119Z","time spent":"780.224716ms","remote":"127.0.0.1:33624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:51:08.456283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.828143ms","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-02-10T18:51:08.456382Z","caller":"traceutil/trace.go:171","msg":"trace[1806130621] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:544; }","duration":"771.948725ms","start":"2026-02-10T18:51:07.684424Z","end":"2026-02-10T18:51:08.456373Z","steps":["trace[1806130621] 'agreement among raft nodes before linearized reading' (duration: 771.826812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:08.45642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:07.684408Z","time spent":"772.004957ms","remote":"127.0.0.1:33726","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-02-10T18:51:08.456568Z","caller":"traceutil/trace.go:171","msg":"trace[1964014272] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"185.39181ms","start":"2026-02-10T18:51:08.27117Z","end":"2026-02-10T18:51:08.456561Z","steps":["trace[1964014272] 'process raft request' (duration: 185.030371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:08.456728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.520742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-10T18:51:08.457652Z","caller":"traceutil/trace.go:171","msg":"trace[1731509147] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:544; }","duration":"392.472485ms","start":"2026-02-10T18:51:08.065169Z","end":"2026-02-10T18:51:08.457641Z","steps":["trace[1731509147] 'agreement among raft nodes before linearized reading' (duration: 391.486071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:08.457705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:08.065157Z","time spent":"392.537437ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-10T18:51:10.298009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.21997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:51:10.298087Z","caller":"traceutil/trace.go:171","msg":"trace[1619117938] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"262.312823ms","start":"2026-02-10T18:51:10.035755Z","end":"2026-02-10T18:51:10.298068Z","steps":["trace[1619117938] 'range keys from in-memory index tree' (duration: 262.082827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:10.298506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.842121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-10T18:51:10.298575Z","caller":"traceutil/trace.go:171","msg":"trace[1144588613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:555; }","duration":"159.960934ms","start":"2026-02-10T18:51:10.138596Z","end":"2026-02-10T18:51:10.298557Z","steps":["trace[1144588613] 'range keys from in-memory index tree' (duration: 159.625506ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:51:11.725279Z","caller":"traceutil/trace.go:171","msg":"trace[1417225147] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"147.327348ms","start":"2026-02-10T18:51:11.577935Z","end":"2026-02-10T18:51:11.725262Z","steps":["trace[1417225147] 'process raft request' (duration: 88.659547ms)","trace[1417225147] 'compare' (duration: 57.980624ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:51:11.724762Z","caller":"traceutil/trace.go:171","msg":"trace[1002575613] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"119.606567ms","start":"2026-02-10T18:51:11.605132Z","end":"2026-02-10T18:51:11.724738Z","steps":["trace[1002575613] 'process raft request' (duration: 119.563386ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:51:11.964964Z","caller":"traceutil/trace.go:171","msg":"trace[2081339587] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"130.535092ms","start":"2026-02-10T18:51:11.834411Z","end":"2026-02-10T18:51:11.964946Z","steps":["trace[2081339587] 'process raft request' (duration: 130.498171ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:51:11.965158Z","caller":"traceutil/trace.go:171","msg":"trace[1518591646] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"156.582332ms","start":"2026-02-10T18:51:11.808548Z","end":"2026-02-10T18:51:11.96513Z","steps":["trace[1518591646] 'process raft request' (duration: 70.099257ms)","trace[1518591646] 'compare' (duration: 86.115026ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:51:11.965212Z","caller":"traceutil/trace.go:171","msg":"trace[1125685253] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"144.5215ms","start":"2026-02-10T18:51:11.820683Z","end":"2026-02-10T18:51:11.965205Z","steps":["trace[1125685253] 'read index received' (duration: 57.905082ms)","trace[1125685253] 'applied index is now lower than readState.Index' (duration: 86.615638ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:51:11.96535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.652433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-znwkd\" ","response":"range_response_count:1 size:4635"} {"level":"info","ts":"2026-02-10T18:51:11.965374Z","caller":"traceutil/trace.go:171","msg":"trace[1280872165] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-znwkd; range_end:; response_count:1; response_revision:588; }","duration":"144.735886ms","start":"2026-02-10T18:51:11.820629Z","end":"2026-02-10T18:51:11.965365Z","steps":["trace[1280872165] 'agreement among raft nodes before linearized reading' (duration: 144.601702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:12.207117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.425082ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:51:12.207212Z","caller":"traceutil/trace.go:171","msg":"trace[492079864] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"171.838461ms","start":"2026-02-10T18:51:12.035358Z","end":"2026-02-10T18:51:12.207196Z","steps":["trace[492079864] 'read index received' (duration: 14.04958ms)","trace[492079864] 'applied index is now lower than readState.Index' (duration: 157.787691ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:51:12.207984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.61946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:51:12.208103Z","caller":"traceutil/trace.go:171","msg":"trace[1085534469] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"174.24463ms","start":"2026-02-10T18:51:12.03375Z","end":"2026-02-10T18:51:12.207994Z","steps":["trace[1085534469] 'process raft request' (duration: 53.855824ms)","trace[1085534469] 'compare' (duration: 119.33984ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:51:12.208031Z","caller":"traceutil/trace.go:171","msg":"trace[256755814] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"172.686132ms","start":"2026-02-10T18:51:12.035332Z","end":"2026-02-10T18:51:12.208018Z","steps":["trace[256755814] 'agreement among raft nodes before linearized reading' (duration: 172.617481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:12.209275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.469311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zt5j9\" ","response":"range_response_count:1 size:17411"} {"level":"info","ts":"2026-02-10T18:51:12.210074Z","caller":"traceutil/trace.go:171","msg":"trace[1875984895] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zt5j9; range_end:; response_count:1; response_revision:593; }","duration":"155.303492ms","start":"2026-02-10T18:51:12.054758Z","end":"2026-02-10T18:51:12.210061Z","steps":["trace[1875984895] 'agreement among raft nodes before linearized reading' (duration: 154.445041ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:51:12.209339Z","caller":"traceutil/trace.go:171","msg":"trace[1146650854] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"154.083652ms","start":"2026-02-10T18:51:12.055245Z","end":"2026-02-10T18:51:12.209329Z","steps":["trace[1146650854] 'process raft request' (duration: 153.853486ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:51:12.401178Z","caller":"traceutil/trace.go:171","msg":"trace[1429669734] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"178.159055ms","start":"2026-02-10T18:51:12.222997Z","end":"2026-02-10T18:51:12.401156Z","steps":["trace[1429669734] 'process raft request' (duration: 177.447518ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:51:12.401565Z","caller":"traceutil/trace.go:171","msg":"trace[123259569] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"134.20256ms","start":"2026-02-10T18:51:12.267348Z","end":"2026-02-10T18:51:12.40155Z","steps":["trace[123259569] 'process raft request' (duration: 133.329079ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:51:12.604524Z","caller":"traceutil/trace.go:171","msg":"trace[172300299] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"150.859263ms","start":"2026-02-10T18:51:12.453643Z","end":"2026-02-10T18:51:12.604502Z","steps":["trace[172300299] 'process raft request' (duration: 80.262844ms)","trace[172300299] 'compare' (duration: 70.230831ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:51:12.604687Z","caller":"traceutil/trace.go:171","msg":"trace[144300631] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"114.272088ms","start":"2026-02-10T18:51:12.490405Z","end":"2026-02-10T18:51:12.604677Z","steps":["trace[144300631] 'read index received' (duration: 731.908µs)","trace[144300631] 'applied index is now lower than readState.Index' (duration: 113.53912ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:51:12.60503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.607046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-10T18:51:12.605071Z","caller":"traceutil/trace.go:171","msg":"trace[2064337297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:600; }","duration":"114.662567ms","start":"2026-02-10T18:51:12.490399Z","end":"2026-02-10T18:51:12.605062Z","steps":["trace[2064337297] 'agreement among raft nodes before linearized reading' (duration: 114.502453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:12.605288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.723569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-10T18:51:12.605319Z","caller":"traceutil/trace.go:171","msg":"trace[120418193] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:600; }","duration":"107.7704ms","start":"2026-02-10T18:51:12.49754Z","end":"2026-02-10T18:51:12.605311Z","steps":["trace[120418193] 'agreement among raft nodes before linearized reading' (duration: 107.686258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:12.605675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.165627ms","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-02-10T18:51:12.605714Z","caller":"traceutil/trace.go:171","msg":"trace[960867128] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:600; }","duration":"105.204227ms","start":"2026-02-10T18:51:12.500501Z","end":"2026-02-10T18:51:12.605705Z","steps":["trace[960867128] 'agreement among raft nodes before linearized reading' (duration: 105.113175ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:51:13.227503Z","caller":"traceutil/trace.go:171","msg":"trace[1668652339] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"115.258981ms","start":"2026-02-10T18:51:13.112215Z","end":"2026-02-10T18:51:13.227474Z","steps":["trace[1668652339] 'process raft request' (duration: 115.051866ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:51:13.351882Z","caller":"traceutil/trace.go:171","msg":"trace[1929527897] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"101.386805ms","start":"2026-02-10T18:51:13.250472Z","end":"2026-02-10T18:51:13.351859Z","steps":["trace[1929527897] 'process raft request' (duration: 53.061065ms)","trace[1929527897] 'compare' (duration: 48.237078ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:51:16.706569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.432624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:51:16.70667Z","caller":"traceutil/trace.go:171","msg":"trace[1905357325] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:647; }","duration":"283.536706ms","start":"2026-02-10T18:51:16.4231Z","end":"2026-02-10T18:51:16.706637Z","steps":["trace[1905357325] 'range keys from in-memory index tree' (duration: 283.383494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:16.706679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.036638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:51:16.706807Z","caller":"traceutil/trace.go:171","msg":"trace[173688884] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:647; }","duration":"310.189763ms","start":"2026-02-10T18:51:16.396586Z","end":"2026-02-10T18:51:16.706776Z","steps":["trace[173688884] 'range keys from in-memory index tree' (duration: 310.002298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:16.706896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:16.396582Z","time spent":"310.294605ms","remote":"127.0.0.1:57150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-10T18:51:16.707171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.859695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:51:16.707246Z","caller":"traceutil/trace.go:171","msg":"trace[2030282554] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:647; }","duration":"283.940887ms","start":"2026-02-10T18:51:16.423289Z","end":"2026-02-10T18:51:16.70723Z","steps":["trace[2030282554] 'count revisions from in-memory index tree' (duration: 283.826674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:16.707753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.802983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:51:16.707786Z","caller":"traceutil/trace.go:171","msg":"trace[1764894278] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:647; }","duration":"283.865505ms","start":"2026-02-10T18:51:16.423912Z","end":"2026-02-10T18:51:16.707778Z","steps":["trace[1764894278] 'range keys from in-memory index tree' (duration: 283.748432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:16.708306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.74729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1667"} {"level":"info","ts":"2026-02-10T18:51:16.70835Z","caller":"traceutil/trace.go:171","msg":"trace[559221014] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:647; }","duration":"325.813281ms","start":"2026-02-10T18:51:16.382518Z","end":"2026-02-10T18:51:16.708331Z","steps":["trace[559221014] 'range keys from in-memory index tree' (duration: 325.679167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:16.708371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:16.382501Z","time spent":"325.864852ms","remote":"127.0.0.1:44350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":1690,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" "} {"level":"warn","ts":"2026-02-10T18:51:16.709087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.432708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1667"} {"level":"info","ts":"2026-02-10T18:51:16.709121Z","caller":"traceutil/trace.go:171","msg":"trace[1848935564] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:647; }","duration":"324.494209ms","start":"2026-02-10T18:51:16.384611Z","end":"2026-02-10T18:51:16.709105Z","steps":["trace[1848935564] 'range keys from in-memory index tree' (duration: 324.376356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:16.709143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:16.384596Z","time spent":"324.5393ms","remote":"127.0.0.1:44350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":1690,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" "} {"level":"warn","ts":"2026-02-10T18:51:16.709424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.858837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:51:16.709454Z","caller":"traceutil/trace.go:171","msg":"trace[140274418] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:647; }","duration":"312.893128ms","start":"2026-02-10T18:51:16.396546Z","end":"2026-02-10T18:51:16.709439Z","steps":["trace[140274418] 'count revisions from in-memory index tree' (duration: 312.838037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:16.709474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:16.396542Z","time spent":"312.925559ms","remote":"127.0.0.1:57150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-10T18:51:16.70993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.501083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:51:16.709961Z","caller":"traceutil/trace.go:171","msg":"trace[1427732362] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:647; }","duration":"313.526184ms","start":"2026-02-10T18:51:16.396421Z","end":"2026-02-10T18:51:16.709947Z","steps":["trace[1427732362] 'range keys from in-memory index tree' (duration: 313.314008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:16.70998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:16.396396Z","time spent":"313.578194ms","remote":"127.0.0.1:57150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 "} {"level":"info","ts":"2026-02-10T18:51:16.711379Z","caller":"traceutil/trace.go:171","msg":"trace[926419104] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:672; }","duration":"245.570847ms","start":"2026-02-10T18:51:16.465766Z","end":"2026-02-10T18:51:16.711337Z","steps":["trace[926419104] 'read index received' (duration: 243.506757ms)","trace[926419104] 'applied index is now lower than readState.Index' (duration: 2.06211ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:51:16.714799Z","caller":"traceutil/trace.go:171","msg":"trace[2140118649] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"266.415833ms","start":"2026-02-10T18:51:16.448361Z","end":"2026-02-10T18:51:16.714777Z","steps":["trace[2140118649] 'process raft request' (duration: 262.108328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:16.714951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.588096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:51:16.714976Z","caller":"traceutil/trace.go:171","msg":"trace[426070542] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:648; }","duration":"164.649258ms","start":"2026-02-10T18:51:16.550319Z","end":"2026-02-10T18:51:16.714969Z","steps":["trace[426070542] 'agreement among raft nodes before linearized reading' (duration: 164.603017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:16.715109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.344048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-10T18:51:16.715129Z","caller":"traceutil/trace.go:171","msg":"trace[53352172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:648; }","duration":"249.40649ms","start":"2026-02-10T18:51:16.465716Z","end":"2026-02-10T18:51:16.715123Z","steps":["trace[53352172] 'agreement among raft nodes before linearized reading' (duration: 249.332688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:16.715269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.07235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-10T18:51:16.715308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.142642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:51:16.715337Z","caller":"traceutil/trace.go:171","msg":"trace[758968147] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:648; }","duration":"149.182503ms","start":"2026-02-10T18:51:16.566141Z","end":"2026-02-10T18:51:16.715323Z","steps":["trace[758968147] 'agreement among raft nodes before linearized reading' (duration: 149.109771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:16.719195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.165369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:51:16.719317Z","caller":"traceutil/trace.go:171","msg":"trace[732912431] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:648; }","duration":"153.284252ms","start":"2026-02-10T18:51:16.56602Z","end":"2026-02-10T18:51:16.719304Z","steps":["trace[732912431] 'agreement among raft nodes before linearized reading' (duration: 153.150179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:16.719484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.050764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:51:16.719535Z","caller":"traceutil/trace.go:171","msg":"trace[184465830] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:648; }","duration":"169.106175ms","start":"2026-02-10T18:51:16.550417Z","end":"2026-02-10T18:51:16.719523Z","steps":["trace[184465830] 'agreement among raft nodes before linearized reading' (duration: 169.045364ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:51:16.715308Z","caller":"traceutil/trace.go:171","msg":"trace[1238417110] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:648; }","duration":"149.247255ms","start":"2026-02-10T18:51:16.566044Z","end":"2026-02-10T18:51:16.715291Z","steps":["trace[1238417110] 'agreement among raft nodes before linearized reading' (duration: 149.129212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:18.73209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.465338ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:51:18.733082Z","caller":"traceutil/trace.go:171","msg":"trace[1920619683] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"409.270922ms","start":"2026-02-10T18:51:18.323783Z","end":"2026-02-10T18:51:18.733054Z","steps":["trace[1920619683] 'process raft request' (duration: 128.074182ms)","trace[1920619683] 'compare' (duration: 279.102189ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:51:18.733169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:18.323748Z","time spent":"409.380345ms","remote":"127.0.0.1:33932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14311,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:51:18.859472Z","caller":"traceutil/trace.go:171","msg":"trace[1287281593] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"530.716513ms","start":"2026-02-10T18:51:18.328705Z","end":"2026-02-10T18:51:18.859421Z","steps":["trace[1287281593] 'process raft request' (duration: 527.559277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:18.859697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:18.328698Z","time spent":"530.844676ms","remote":"127.0.0.1:33618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4085,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:51:18.863986Z","caller":"traceutil/trace.go:171","msg":"trace[2118950625] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:686; }","duration":"111.655814ms","start":"2026-02-10T18:51:18.752313Z","end":"2026-02-10T18:51:18.863968Z","steps":["trace[2118950625] 'read index received' (duration: 104.125971ms)","trace[2118950625] 'applied index is now lower than readState.Index' (duration: 7.529173ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:51:18.864016Z","caller":"traceutil/trace.go:171","msg":"trace[1840073844] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"344.501764ms","start":"2026-02-10T18:51:18.519503Z","end":"2026-02-10T18:51:18.864004Z","steps":["trace[1840073844] 'process raft request' (duration: 341.379918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:18.865694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:18.519437Z","time spent":"346.177474ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:51:18.865779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.922085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-10T18:51:18.865834Z","caller":"traceutil/trace.go:171","msg":"trace[648185864] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:664; }","duration":"112.978336ms","start":"2026-02-10T18:51:18.752847Z","end":"2026-02-10T18:51:18.865826Z","steps":["trace[648185864] 'agreement among raft nodes before linearized reading' (duration: 112.876563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:18.864261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.944772ms","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-02-10T18:51:18.865938Z","caller":"traceutil/trace.go:171","msg":"trace[1918588086] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:664; }","duration":"113.677933ms","start":"2026-02-10T18:51:18.752252Z","end":"2026-02-10T18:51:18.86593Z","steps":["trace[1918588086] 'agreement among raft nodes before linearized reading' (duration: 111.909641ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:51:18.864451Z","caller":"traceutil/trace.go:171","msg":"trace[1175661943] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"316.2918ms","start":"2026-02-10T18:51:18.54815Z","end":"2026-02-10T18:51:18.864442Z","steps":["trace[1175661943] 'process raft request' (duration: 315.770498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:18.867297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:18.548111Z","time spent":"319.094778ms","remote":"127.0.0.1:57130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2260,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T18:51:29.349405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.973428ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:51:29.349504Z","caller":"traceutil/trace.go:171","msg":"trace[742284290] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"377.612045ms","start":"2026-02-10T18:51:28.971861Z","end":"2026-02-10T18:51:29.349473Z","steps":["trace[742284290] 'process raft request' (duration: 36.482303ms)","trace[742284290] 'compare' (duration: 340.867816ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:51:29.349547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:28.971842Z","time spent":"377.686247ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:51:29.972485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.493914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:51:29.972678Z","caller":"traceutil/trace.go:171","msg":"trace[474918205] linearizableReadLoop","detail":"{readStateIndex:763; appliedIndex:760; }","duration":"531.750289ms","start":"2026-02-10T18:51:29.440915Z","end":"2026-02-10T18:51:29.972665Z","steps":["trace[474918205] 'read index received' (duration: 200.965948ms)","trace[474918205] 'applied index is now lower than readState.Index' (duration: 330.783511ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:51:29.97268Z","caller":"traceutil/trace.go:171","msg":"trace[2031490478] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"1.00047075s","start":"2026-02-10T18:51:28.972186Z","end":"2026-02-10T18:51:29.972657Z","steps":["trace[2031490478] 'process raft request' (duration: 669.73477ms)","trace[2031490478] 'compare' (duration: 330.183956ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:51:29.972794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.883412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-10T18:51:29.972791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:28.972175Z","time spent":"1.000553543s","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:51:29.972815Z","caller":"traceutil/trace.go:171","msg":"trace[619000743] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:735; }","duration":"531.942923ms","start":"2026-02-10T18:51:29.440865Z","end":"2026-02-10T18:51:29.972808Z","steps":["trace[619000743] 'agreement among raft nodes before linearized reading' (duration: 531.835531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:29.97284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:29.440844Z","time spent":"531.990254ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-10T18:51:29.972971Z","caller":"traceutil/trace.go:171","msg":"trace[459681989] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"1.000726016s","start":"2026-02-10T18:51:28.972236Z","end":"2026-02-10T18:51:29.972962Z","steps":["trace[459681989] 'process raft request' (duration: 1.000325406s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:29.973005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:28.972231Z","time spent":"1.000754857s","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:51:29.973086Z","caller":"traceutil/trace.go:171","msg":"trace[1905408846] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"998.41152ms","start":"2026-02-10T18:51:28.974669Z","end":"2026-02-10T18:51:29.97308Z","steps":["trace[1905408846] 'process raft request' (duration: 997.966869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:29.973113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:28.97466Z","time spent":"998.438171ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:51:30.470634Z","caller":"traceutil/trace.go:171","msg":"trace[1522075541] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:763; }","duration":"436.325808ms","start":"2026-02-10T18:51:30.034282Z","end":"2026-02-10T18:51:30.470608Z","steps":["trace[1522075541] 'read index received' (duration: 433.695734ms)","trace[1522075541] 'applied index is now lower than readState.Index' (duration: 2.628774ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:51:30.470861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.551673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:51:30.470904Z","caller":"traceutil/trace.go:171","msg":"trace[1829427663] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:736; }","duration":"436.612725ms","start":"2026-02-10T18:51:30.034278Z","end":"2026-02-10T18:51:30.47089Z","steps":["trace[1829427663] 'agreement among raft nodes before linearized reading' (duration: 436.44661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:30.471121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:30.034248Z","time spent":"436.8573ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T18:51:30.471532Z","caller":"traceutil/trace.go:171","msg":"trace[2090230925] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"493.748668ms","start":"2026-02-10T18:51:29.977689Z","end":"2026-02-10T18:51:30.471438Z","steps":["trace[2090230925] 'process raft request' (duration: 490.379306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:30.471641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:29.977671Z","time spent":"493.901432ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:51:31.477627Z","caller":"traceutil/trace.go:171","msg":"trace[1910606958] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"122.274902ms","start":"2026-02-10T18:51:31.355335Z","end":"2026-02-10T18:51:31.47761Z","steps":["trace[1910606958] 'process raft request' (duration: 122.158299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:32.757979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.755314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T18:51:32.758047Z","caller":"traceutil/trace.go:171","msg":"trace[1357373490] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:739; }","duration":"537.864647ms","start":"2026-02-10T18:51:32.220166Z","end":"2026-02-10T18:51:32.75803Z","steps":["trace[1357373490] 'count revisions from in-memory index tree' (duration: 537.696242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:32.758088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:32.220148Z","time spent":"537.931708ms","remote":"127.0.0.1:33908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":2,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-10T18:51:32.758247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.684461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:51:32.758302Z","caller":"traceutil/trace.go:171","msg":"trace[927158828] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:739; }","duration":"793.793004ms","start":"2026-02-10T18:51:31.964495Z","end":"2026-02-10T18:51:32.758288Z","steps":["trace[927158828] 'count revisions from in-memory index tree' (duration: 793.61441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:32.758316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.768686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-10T18:51:32.758331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:31.964473Z","time spent":"793.850956ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-02-10T18:51:32.758344Z","caller":"traceutil/trace.go:171","msg":"trace[1651426172] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:739; }","duration":"348.822198ms","start":"2026-02-10T18:51:32.409509Z","end":"2026-02-10T18:51:32.758331Z","steps":["trace[1651426172] 'count revisions from in-memory index tree' (duration: 348.723045ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:32.758385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:32.409493Z","time spent":"348.885929ms","remote":"127.0.0.1:33676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-10T18:51:32.758554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.724878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-10T18:51:32.758575Z","caller":"traceutil/trace.go:171","msg":"trace[1802984829] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:739; }","duration":"780.77157ms","start":"2026-02-10T18:51:31.977797Z","end":"2026-02-10T18:51:32.758568Z","steps":["trace[1802984829] 'range keys from in-memory index tree' (duration: 780.598095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:32.758594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:31.97778Z","time spent":"780.81047ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-10T18:51:32.758723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.387994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-10T18:51:32.758738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.200716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-10T18:51:32.758745Z","caller":"traceutil/trace.go:171","msg":"trace[739028394] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:739; }","duration":"281.437176ms","start":"2026-02-10T18:51:32.477301Z","end":"2026-02-10T18:51:32.758738Z","steps":["trace[739028394] 'range keys from in-memory index tree' (duration: 281.275622ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:51:32.758769Z","caller":"traceutil/trace.go:171","msg":"trace[849723925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:739; }","duration":"780.230566ms","start":"2026-02-10T18:51:31.978533Z","end":"2026-02-10T18:51:32.758763Z","steps":["trace[849723925] 'range keys from in-memory index tree' (duration: 780.134294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:32.758788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:31.978502Z","time spent":"780.282167ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T18:51:32.758922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.563444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:51:32.758939Z","caller":"traceutil/trace.go:171","msg":"trace[205225596] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:739; }","duration":"723.591794ms","start":"2026-02-10T18:51:32.035342Z","end":"2026-02-10T18:51:32.758933Z","steps":["trace[205225596] 'range keys from in-memory index tree' (duration: 723.528933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:32.758954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:32.035334Z","time spent":"723.616935ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T18:51:33.171326Z","caller":"traceutil/trace.go:171","msg":"trace[1388054993] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"405.259544ms","start":"2026-02-10T18:51:32.766041Z","end":"2026-02-10T18:51:33.171301Z","steps":["trace[1388054993] 'process raft request' (duration: 405.142832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:33.171431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:32.766029Z","time spent":"405.345136ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:51:33.21452Z","caller":"traceutil/trace.go:171","msg":"trace[594813978] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"446.598946ms","start":"2026-02-10T18:51:32.767915Z","end":"2026-02-10T18:51:33.214514Z","steps":["trace[594813978] 'process raft request' (duration: 446.490403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:33.214574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:32.767895Z","time spent":"446.642347ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:51:33.214801Z","caller":"traceutil/trace.go:171","msg":"trace[157449565] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"445.787706ms","start":"2026-02-10T18:51:32.769008Z","end":"2026-02-10T18:51:33.214795Z","steps":["trace[157449565] 'process raft request' (duration: 445.453208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:33.214837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:32.769Z","time spent":"445.820608ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:51:33.214486Z","caller":"traceutil/trace.go:171","msg":"trace[944699702] linearizableReadLoop","detail":"{readStateIndex:771; appliedIndex:769; }","duration":"184.134719ms","start":"2026-02-10T18:51:33.030343Z","end":"2026-02-10T18:51:33.214477Z","steps":["trace[944699702] 'read index received' (duration: 141.517357ms)","trace[944699702] 'applied index is now lower than readState.Index' (duration: 42.617042ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:51:33.215053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.515075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T18:51:33.215073Z","caller":"traceutil/trace.go:171","msg":"trace[1585036986] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:742; }","duration":"138.554015ms","start":"2026-02-10T18:51:33.076513Z","end":"2026-02-10T18:51:33.215067Z","steps":["trace[1585036986] 'agreement among raft nodes before linearized reading' (duration: 138.502804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:33.215395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.049731ms","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-02-10T18:51:33.215413Z","caller":"traceutil/trace.go:171","msg":"trace[2052143427] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:742; }","duration":"185.105223ms","start":"2026-02-10T18:51:33.030302Z","end":"2026-02-10T18:51:33.215408Z","steps":["trace[2052143427] 'agreement among raft nodes before linearized reading' (duration: 184.680922ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:51:33.393212Z","caller":"traceutil/trace.go:171","msg":"trace[1579286829] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"175.580882ms","start":"2026-02-10T18:51:33.217608Z","end":"2026-02-10T18:51:33.393189Z","steps":["trace[1579286829] 'process raft request' (duration: 162.814783ms)","trace[1579286829] 'compare' (duration: 12.577505ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:51:39.743222Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255048295549034,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T18:51:39.84201Z","caller":"traceutil/trace.go:171","msg":"trace[1092188311] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"644.207903ms","start":"2026-02-10T18:51:39.19778Z","end":"2026-02-10T18:51:39.841987Z","steps":["trace[1092188311] 'process raft request' (duration: 644.013688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:39.842169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:39.197771Z","time spent":"644.326465ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:51:39.84245Z","caller":"traceutil/trace.go:171","msg":"trace[2125505110] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:792; }","duration":"599.462289ms","start":"2026-02-10T18:51:39.242978Z","end":"2026-02-10T18:51:39.84244Z","steps":["trace[2125505110] 'read index received' (duration: 599.458429ms)","trace[2125505110] 'applied index is now lower than readState.Index' (duration: 3.11µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:51:39.842623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.467155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-10T18:51:39.842656Z","caller":"traceutil/trace.go:171","msg":"trace[208733379] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:762; }","duration":"584.521947ms","start":"2026-02-10T18:51:39.258123Z","end":"2026-02-10T18:51:39.842645Z","steps":["trace[208733379] 'agreement among raft nodes before linearized reading' (duration: 584.375533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:39.842683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:39.258111Z","time spent":"584.565807ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-10T18:51:39.842907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.9335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-10T18:51:39.842934Z","caller":"traceutil/trace.go:171","msg":"trace[138653284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:762; }","duration":"599.96062ms","start":"2026-02-10T18:51:39.242965Z","end":"2026-02-10T18:51:39.842926Z","steps":["trace[138653284] 'agreement among raft nodes before linearized reading' (duration: 599.886369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:39.842958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:39.242899Z","time spent":"600.053173ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T18:51:39.8431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.177008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-10T18:51:39.843128Z","caller":"traceutil/trace.go:171","msg":"trace[1420846919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:762; }","duration":"328.204309ms","start":"2026-02-10T18:51:39.514916Z","end":"2026-02-10T18:51:39.84312Z","steps":["trace[1420846919] 'agreement among raft nodes before linearized reading' (duration: 328.132607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:39.843153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:39.514881Z","time spent":"328.265161ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T18:51:39.843293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.999993ms","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-02-10T18:51:39.84332Z","caller":"traceutil/trace.go:171","msg":"trace[828398851] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:762; }","duration":"387.048174ms","start":"2026-02-10T18:51:39.456262Z","end":"2026-02-10T18:51:39.84331Z","steps":["trace[828398851] 'agreement among raft nodes before linearized reading' (duration: 386.981312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:39.843346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:39.45625Z","time spent":"387.089535ms","remote":"127.0.0.1:33726","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-02-10T18:51:40.123121Z","caller":"traceutil/trace.go:171","msg":"trace[237690132] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"273.905574ms","start":"2026-02-10T18:51:39.849199Z","end":"2026-02-10T18:51:40.123104Z","steps":["trace[237690132] 'process raft request' (duration: 273.815272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:41.002454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.286558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:51:41.003291Z","caller":"traceutil/trace.go:171","msg":"trace[1024122970] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"1.153979238s","start":"2026-02-10T18:51:39.84929Z","end":"2026-02-10T18:51:41.003269Z","steps":["trace[1024122970] 'process raft request' (duration: 668.787718ms)","trace[1024122970] 'compare' (duration: 483.464119ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:51:41.003567Z","caller":"traceutil/trace.go:171","msg":"trace[2127113921] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:793; }","duration":"970.044053ms","start":"2026-02-10T18:51:40.033498Z","end":"2026-02-10T18:51:41.003542Z","steps":["trace[2127113921] 'read index received' (duration: 89.977359ms)","trace[2127113921] 'applied index is now lower than readState.Index' (duration: 880.066114ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:51:41.003592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:39.849284Z","time spent":"1.154278155s","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:51:41.003687Z","caller":"traceutil/trace.go:171","msg":"trace[780498358] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"1.154360477s","start":"2026-02-10T18:51:39.84932Z","end":"2026-02-10T18:51:41.003681Z","steps":["trace[780498358] 'process raft request' (duration: 1.153784043s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:41.003742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:39.849316Z","time spent":"1.154401658s","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:51:41.003898Z","caller":"traceutil/trace.go:171","msg":"trace[1061745305] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"1.154045029s","start":"2026-02-10T18:51:39.849846Z","end":"2026-02-10T18:51:41.003891Z","steps":["trace[1061745305] 'process raft request' (duration: 1.1536584s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:41.003934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:39.849767Z","time spent":"1.154147562s","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:51:41.004094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.613477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:51:41.004116Z","caller":"traceutil/trace.go:171","msg":"trace[1574667493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:766; }","duration":"970.656098ms","start":"2026-02-10T18:51:40.033453Z","end":"2026-02-10T18:51:41.004109Z","steps":["trace[1574667493] 'agreement among raft nodes before linearized reading' (duration: 970.619547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:41.004159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:40.033438Z","time spent":"970.71694ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T18:51:41.033739Z","caller":"traceutil/trace.go:171","msg":"trace[1735210625] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"764.030454ms","start":"2026-02-10T18:51:40.269696Z","end":"2026-02-10T18:51:41.033726Z","steps":["trace[1735210625] 'process raft request' (duration: 763.780178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:41.03382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:40.269687Z","time spent":"764.086885ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:51:41.034013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.381963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.12\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-10T18:51:41.034029Z","caller":"traceutil/trace.go:171","msg":"trace[66104618] range","detail":"{range_begin:/registry/masterleases/162.253.55.12; range_end:; response_count:1; response_revision:767; }","duration":"354.414213ms","start":"2026-02-10T18:51:40.67961Z","end":"2026-02-10T18:51:41.034024Z","steps":["trace[66104618] 'agreement among raft nodes before linearized reading' (duration: 354.355392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:41.034041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:40.679595Z","time spent":"354.442794ms","remote":"127.0.0.1:33478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.12\" "} {"level":"warn","ts":"2026-02-10T18:51:41.034112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.478343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:51:41.034124Z","caller":"traceutil/trace.go:171","msg":"trace[223578589] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:767; }","duration":"766.511584ms","start":"2026-02-10T18:51:40.267609Z","end":"2026-02-10T18:51:41.03412Z","steps":["trace[223578589] 'agreement among raft nodes before linearized reading' (duration: 766.488464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:41.034136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:40.267597Z","time spent":"766.535215ms","remote":"127.0.0.1:33748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-02-10T18:51:41.034205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.631192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:51:41.034223Z","caller":"traceutil/trace.go:171","msg":"trace[918285173] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:767; }","duration":"803.668394ms","start":"2026-02-10T18:51:40.230548Z","end":"2026-02-10T18:51:41.034216Z","steps":["trace[918285173] 'agreement among raft nodes before linearized reading' (duration: 803.642523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:41.034239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:40.230536Z","time spent":"803.699254ms","remote":"127.0.0.1:33520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-02-10T18:51:41.287352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.201095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:51:41.287425Z","caller":"traceutil/trace.go:171","msg":"trace[1882628531] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"237.629805ms","start":"2026-02-10T18:51:41.049769Z","end":"2026-02-10T18:51:41.287399Z","steps":["trace[1882628531] 'process raft request' (duration: 97.336327ms)","trace[1882628531] 'compare' (duration: 140.067432ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:51:43.216982Z","caller":"traceutil/trace.go:171","msg":"trace[589754365] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:803; }","duration":"136.383563ms","start":"2026-02-10T18:51:43.080579Z","end":"2026-02-10T18:51:43.216962Z","steps":["trace[589754365] 'read index received' (duration: 136.26427ms)","trace[589754365] 'applied index is now lower than readState.Index' (duration: 118.443µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:51:43.217064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.466625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:51:43.217086Z","caller":"traceutil/trace.go:171","msg":"trace[937268394] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:773; }","duration":"136.526076ms","start":"2026-02-10T18:51:43.080554Z","end":"2026-02-10T18:51:43.21708Z","steps":["trace[937268394] 'agreement among raft nodes before linearized reading' (duration: 136.475284ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:51:43.217225Z","caller":"traceutil/trace.go:171","msg":"trace[102619042] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"165.783295ms","start":"2026-02-10T18:51:43.051421Z","end":"2026-02-10T18:51:43.217204Z","steps":["trace[102619042] 'process raft request' (duration: 165.434616ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:51:52.201089Z","caller":"traceutil/trace.go:171","msg":"trace[1119684406] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:829; }","duration":"164.921494ms","start":"2026-02-10T18:51:52.036156Z","end":"2026-02-10T18:51:52.201077Z","steps":["trace[1119684406] 'read index received' (duration: 164.810951ms)","trace[1119684406] 'applied index is now lower than readState.Index' (duration: 109.943µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:51:52.201223Z","caller":"traceutil/trace.go:171","msg":"trace[1870282665] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"183.668488ms","start":"2026-02-10T18:51:52.017549Z","end":"2026-02-10T18:51:52.201217Z","steps":["trace[1870282665] 'process raft request' (duration: 183.451473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:52.201357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.197711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:51:52.201376Z","caller":"traceutil/trace.go:171","msg":"trace[1353325810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"165.255872ms","start":"2026-02-10T18:51:52.036114Z","end":"2026-02-10T18:51:52.20137Z","steps":["trace[1353325810] 'agreement among raft nodes before linearized reading' (duration: 165.217841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:53.766535Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255048295549139,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T18:51:53.983919Z","caller":"traceutil/trace.go:171","msg":"trace[1408141521] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:831; }","duration":"718.163613ms","start":"2026-02-10T18:51:53.265739Z","end":"2026-02-10T18:51:53.983903Z","steps":["trace[1408141521] 'read index received' (duration: 718.00684ms)","trace[1408141521] 'applied index is now lower than readState.Index' (duration: 156.203µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:51:53.983994Z","caller":"traceutil/trace.go:171","msg":"trace[1203144570] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"735.792769ms","start":"2026-02-10T18:51:53.248193Z","end":"2026-02-10T18:51:53.983986Z","steps":["trace[1203144570] 'process raft request' (duration: 735.606625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:53.984062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:53.248184Z","time spent":"735.82615ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:51:54.827862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.483849376s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-10T18:51:54.82792Z","caller":"traceutil/trace.go:171","msg":"trace[2074574854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:799; }","duration":"1.483929119s","start":"2026-02-10T18:51:53.343974Z","end":"2026-02-10T18:51:54.827903Z","steps":["trace[2074574854] 'agreement among raft nodes before linearized reading' (duration: 640.170044ms)","trace[2074574854] 'range keys from in-memory index tree' (duration: 843.617341ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:51:54.827948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:53.343963Z","time spent":"1.48397999s","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T18:51:54.828137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.562397109s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-10T18:51:54.828157Z","caller":"traceutil/trace.go:171","msg":"trace[1586897405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:799; }","duration":"1.56244498s","start":"2026-02-10T18:51:53.265705Z","end":"2026-02-10T18:51:54.82815Z","steps":["trace[1586897405] 'agreement among raft nodes before linearized reading' (duration: 718.45198ms)","trace[1586897405] 'range keys from in-memory index tree' (duration: 843.937098ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:51:54.828175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:53.265689Z","time spent":"1.562482111s","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T18:51:54.82831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.212464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:51:54.828328Z","caller":"traceutil/trace.go:171","msg":"trace[935739462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:799; }","duration":"794.266866ms","start":"2026-02-10T18:51:54.034056Z","end":"2026-02-10T18:51:54.828323Z","steps":["trace[935739462] 'range keys from in-memory index tree' (duration: 794.142323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:54.828344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:54.034039Z","time spent":"794.301586ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:51:54.82845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.26335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-10T18:51:54.828466Z","caller":"traceutil/trace.go:171","msg":"trace[611947450] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:799; }","duration":"622.317411ms","start":"2026-02-10T18:51:54.206143Z","end":"2026-02-10T18:51:54.828461Z","steps":["trace[611947450] 'range keys from in-memory index tree' (duration: 622.177408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:54.828482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:54.206124Z","time spent":"622.355123ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-10T18:51:54.82858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.293644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-10T18:51:54.828613Z","caller":"traceutil/trace.go:171","msg":"trace[1256599218] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:799; }","duration":"618.348616ms","start":"2026-02-10T18:51:54.210259Z","end":"2026-02-10T18:51:54.828608Z","steps":["trace[1256599218] 'range keys from in-memory index tree' (duration: 618.225842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:54.828629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:54.210248Z","time spent":"618.376856ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-10T18:51:54.834464Z","caller":"traceutil/trace.go:171","msg":"trace[1372922043] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"453.120354ms","start":"2026-02-10T18:51:54.381277Z","end":"2026-02-10T18:51:54.834398Z","steps":["trace[1372922043] 'process raft request' (duration: 453.040132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:54.834567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:54.381253Z","time spent":"453.252867ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:51:56.831213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999139576s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-10T18:51:56.831263Z","caller":"traceutil/trace.go:171","msg":"trace[1175425006] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999238679s","start":"2026-02-10T18:51:54.832013Z","end":"2026-02-10T18:51:56.831251Z","steps":["trace[1175425006] 'agreement among raft nodes before linearized reading' (duration: 1.999165867s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:56.831289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:54.831998Z","time spent":"1.9992862s","remote":"127.0.0.1:33462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/10 18:51:56 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-10T18:51:57.115574Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.143561413s","expected-duration":"1s"} {"level":"info","ts":"2026-02-10T18:51:57.116415Z","caller":"traceutil/trace.go:171","msg":"trace[1841757840] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"2.281557876s","start":"2026-02-10T18:51:54.834833Z","end":"2026-02-10T18:51:57.116391Z","steps":["trace[1841757840] 'read index received' (duration: 77.900037ms)","trace[1841757840] 'applied index is now lower than readState.Index' (duration: 2.203656958s)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:51:57.116711Z","caller":"traceutil/trace.go:171","msg":"trace[1598235550] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"2.282419946s","start":"2026-02-10T18:51:54.834278Z","end":"2026-02-10T18:51:57.116698Z","steps":["trace[1598235550] 'process raft request' (duration: 2.281929204s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:57.116804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:54.83427Z","time spent":"2.282470197s","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:51:57.116971Z","caller":"traceutil/trace.go:171","msg":"trace[1924197307] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"2.276597355s","start":"2026-02-10T18:51:54.840364Z","end":"2026-02-10T18:51:57.116962Z","steps":["trace[1924197307] 'process raft request' (duration: 2.27596027s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:57.11702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:54.840356Z","time spent":"2.276636146s","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:51:57.117142Z","caller":"traceutil/trace.go:171","msg":"trace[573750575] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"2.276184775s","start":"2026-02-10T18:51:54.840949Z","end":"2026-02-10T18:51:57.117133Z","steps":["trace[573750575] 'process raft request' (duration: 2.275412847s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:57.11719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:54.840942Z","time spent":"2.276221466s","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:51:57.819831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.106811ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-10T18:51:58.454175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.237711ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-10T18:51:58.454304Z","caller":"traceutil/trace.go:171","msg":"trace[482086901] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:837; }","duration":"1.337829981s","start":"2026-02-10T18:51:57.116456Z","end":"2026-02-10T18:51:58.454286Z","steps":["trace[482086901] 'read index received' (duration: 158.184621ms)","trace[482086901] 'applied index is now lower than readState.Index' (duration: 1.17964367s)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:51:58.454546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.456200915s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-10T18:51:58.454576Z","caller":"traceutil/trace.go:171","msg":"trace[921226937] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:804; }","duration":"2.456275217s","start":"2026-02-10T18:51:55.998291Z","end":"2026-02-10T18:51:58.454567Z","steps":["trace[921226937] 'agreement among raft nodes before linearized reading' (duration: 2.456079492s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:58.454647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:55.998272Z","time spent":"2.456365609s","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-10T18:51:58.45492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:56.834453Z","time spent":"1.620461814s","remote":"127.0.0.1:33508","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-10T18:51:58.456327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.620868045s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:51:58.456381Z","caller":"traceutil/trace.go:171","msg":"trace[1314302226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"1.621010008s","start":"2026-02-10T18:51:56.835356Z","end":"2026-02-10T18:51:58.456366Z","steps":["trace[1314302226] 'agreement among raft nodes before linearized reading' (duration: 1.620857474s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:58.45651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:56.835342Z","time spent":"1.621095421s","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:51:58.456606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.539992687s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-10T18:51:58.456642Z","caller":"traceutil/trace.go:171","msg":"trace[887251824] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:804; }","duration":"1.540043668s","start":"2026-02-10T18:51:56.916588Z","end":"2026-02-10T18:51:58.456632Z","steps":["trace[887251824] 'agreement among raft nodes before linearized reading' (duration: 1.538720116s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:58.459743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:56.916575Z","time spent":"1.543147543s","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-10T18:51:59.069045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.188476ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:51:59.069195Z","caller":"traceutil/trace.go:171","msg":"trace[1406720906] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:807; }","duration":"389.35115ms","start":"2026-02-10T18:51:58.679826Z","end":"2026-02-10T18:51:59.069177Z","steps":["trace[1406720906] 'range keys from in-memory index tree' (duration: 389.170886ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:51:59.426884Z","caller":"traceutil/trace.go:171","msg":"trace[1533229750] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"293.149849ms","start":"2026-02-10T18:51:59.133712Z","end":"2026-02-10T18:51:59.426861Z","steps":["trace[1533229750] 'process raft request' (duration: 292.638777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:59.669574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.472668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:51:59.669697Z","caller":"traceutil/trace.go:171","msg":"trace[1183934219] linearizableReadLoop","detail":"{readStateIndex:845; appliedIndex:843; }","duration":"239.122451ms","start":"2026-02-10T18:51:59.430563Z","end":"2026-02-10T18:51:59.669685Z","steps":["trace[1183934219] 'read index received' (duration: 61.407737ms)","trace[1183934219] 'applied index is now lower than readState.Index' (duration: 177.714074ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:51:59.669759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.193173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:51:59.669781Z","caller":"traceutil/trace.go:171","msg":"trace[1001025720] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:810; }","duration":"239.217993ms","start":"2026-02-10T18:51:59.430557Z","end":"2026-02-10T18:51:59.669775Z","steps":["trace[1001025720] 'agreement among raft nodes before linearized reading' (duration: 239.163932ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:51:59.669989Z","caller":"traceutil/trace.go:171","msg":"trace[1817167964] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"536.147984ms","start":"2026-02-10T18:51:59.133833Z","end":"2026-02-10T18:51:59.669981Z","steps":["trace[1817167964] 'process raft request' (duration: 358.213305ms)","trace[1817167964] 'compare' (duration: 177.390576ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:51:59.670032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:59.133829Z","time spent":"536.182625ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:51:59.670119Z","caller":"traceutil/trace.go:171","msg":"trace[1264359246] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"535.883499ms","start":"2026-02-10T18:51:59.13423Z","end":"2026-02-10T18:51:59.670113Z","steps":["trace[1264359246] 'process raft request' (duration: 535.419897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:59.670153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:59.134225Z","time spent":"535.911959ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:52:14.004396Z","caller":"traceutil/trace.go:171","msg":"trace[1573796360] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:938; }","duration":"258.282695ms","start":"2026-02-10T18:52:13.746093Z","end":"2026-02-10T18:52:14.004375Z","steps":["trace[1573796360] 'read index received' (duration: 258.067ms)","trace[1573796360] 'applied index is now lower than readState.Index' (duration: 214.385µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:52:14.004584Z","caller":"traceutil/trace.go:171","msg":"trace[629584087] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"385.277332ms","start":"2026-02-10T18:52:13.619293Z","end":"2026-02-10T18:52:14.00457Z","steps":["trace[629584087] 'process raft request' (duration: 384.929143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:14.004738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:13.619284Z","time spent":"385.362674ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:52:14.004743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.142827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-10T18:52:14.004797Z","caller":"traceutil/trace.go:171","msg":"trace[578081086] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:900; }","duration":"254.221209ms","start":"2026-02-10T18:52:13.750559Z","end":"2026-02-10T18:52:14.00478Z","steps":["trace[578081086] 'agreement among raft nodes before linearized reading' (duration: 254.086326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:14.004903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.822848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-10T18:52:14.004921Z","caller":"traceutil/trace.go:171","msg":"trace[1487013238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:900; }","duration":"258.893379ms","start":"2026-02-10T18:52:13.746022Z","end":"2026-02-10T18:52:14.004916Z","steps":["trace[1487013238] 'agreement among raft nodes before linearized reading' (duration: 258.845488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:14.538286Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255048295549371,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:52:14.988947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.784205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:52:14.989014Z","caller":"traceutil/trace.go:171","msg":"trace[1847656162] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"977.00508ms","start":"2026-02-10T18:52:14.011997Z","end":"2026-02-10T18:52:14.989002Z","steps":["trace[1847656162] 'process raft request' (duration: 555.081372ms)","trace[1847656162] 'compare' (duration: 421.522139ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:52:14.989046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:14.011979Z","time spent":"977.052602ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:52:14.989446Z","caller":"traceutil/trace.go:171","msg":"trace[931438355] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:939; }","duration":"951.736592ms","start":"2026-02-10T18:52:14.037695Z","end":"2026-02-10T18:52:14.989432Z","steps":["trace[931438355] 'read index received' (duration: 529.339953ms)","trace[931438355] 'applied index is now lower than readState.Index' (duration: 422.396169ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:52:14.989567Z","caller":"traceutil/trace.go:171","msg":"trace[318979049] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"975.778211ms","start":"2026-02-10T18:52:14.013783Z","end":"2026-02-10T18:52:14.989561Z","steps":["trace[318979049] 'process raft request' (duration: 975.603537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:14.98963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:14.013775Z","time spent":"975.807032ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:52:14.989729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.038589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:52:14.989766Z","caller":"traceutil/trace.go:171","msg":"trace[279875044] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:902; }","duration":"952.098421ms","start":"2026-02-10T18:52:14.037663Z","end":"2026-02-10T18:52:14.989761Z","steps":["trace[279875044] 'agreement among raft nodes before linearized reading' (duration: 952.04975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:14.98978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:14.037649Z","time spent":"952.128421ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:52:14.989884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.691287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T18:52:14.989898Z","caller":"traceutil/trace.go:171","msg":"trace[1323377496] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:902; }","duration":"539.759278ms","start":"2026-02-10T18:52:14.450134Z","end":"2026-02-10T18:52:14.989893Z","steps":["trace[1323377496] 'agreement among raft nodes before linearized reading' (duration: 539.729408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:14.98991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:14.450103Z","time spent":"539.80387ms","remote":"127.0.0.1:33788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-10T18:52:14.990089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.423737ms","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-02-10T18:52:14.990103Z","caller":"traceutil/trace.go:171","msg":"trace[266435721] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:902; }","duration":"402.487098ms","start":"2026-02-10T18:52:14.587612Z","end":"2026-02-10T18:52:14.990099Z","steps":["trace[266435721] 'agreement among raft nodes before linearized reading' (duration: 402.420327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:14.990115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:14.587587Z","time spent":"402.525309ms","remote":"127.0.0.1:33726","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":"warn","ts":"2026-02-10T18:52:14.990229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.163465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-10T18:52:14.990241Z","caller":"traceutil/trace.go:171","msg":"trace[2125555534] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:902; }","duration":"425.215816ms","start":"2026-02-10T18:52:14.565022Z","end":"2026-02-10T18:52:14.990238Z","steps":["trace[2125555534] 'agreement among raft nodes before linearized reading' (duration: 425.169445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:14.990253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:14.565002Z","time spent":"425.247527ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-10T18:52:15.175125Z","caller":"traceutil/trace.go:171","msg":"trace[1662855493] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"180.35208ms","start":"2026-02-10T18:52:14.994756Z","end":"2026-02-10T18:52:15.175108Z","steps":["trace[1662855493] 'process raft request' (duration: 178.2313ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:52:15.478516Z","caller":"traceutil/trace.go:171","msg":"trace[1911877572] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"210.122515ms","start":"2026-02-10T18:52:15.268381Z","end":"2026-02-10T18:52:15.478503Z","steps":["trace[1911877572] 'process raft request' (duration: 209.93504ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:52:19.503702Z","caller":"traceutil/trace.go:171","msg":"trace[1875774609] linearizableReadLoop","detail":"{readStateIndex:952; appliedIndex:951; }","duration":"466.71371ms","start":"2026-02-10T18:52:19.036963Z","end":"2026-02-10T18:52:19.503677Z","steps":["trace[1875774609] 'read index received' (duration: 466.375702ms)","trace[1875774609] 'applied index is now lower than readState.Index' (duration: 336.918µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:52:19.503868Z","caller":"traceutil/trace.go:171","msg":"trace[159677269] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"482.048563ms","start":"2026-02-10T18:52:19.021808Z","end":"2026-02-10T18:52:19.503856Z","steps":["trace[159677269] 'process raft request' (duration: 481.630953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:19.503984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.830815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-10T18:52:19.504031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:19.021789Z","time spent":"482.110414ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:52:19.504128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.16436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-10T18:52:19.504149Z","caller":"traceutil/trace.go:171","msg":"trace[1456632616] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:912; }","duration":"467.202642ms","start":"2026-02-10T18:52:19.036941Z","end":"2026-02-10T18:52:19.504144Z","steps":["trace[1456632616] 'agreement among raft nodes before linearized reading' (duration: 467.12093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:19.504172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:19.036931Z","time spent":"467.236052ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-10T18:52:19.504254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.073622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T18:52:19.504271Z","caller":"traceutil/trace.go:171","msg":"trace[16184704] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:912; }","duration":"215.128824ms","start":"2026-02-10T18:52:19.289137Z","end":"2026-02-10T18:52:19.504266Z","steps":["trace[16184704] 'agreement among raft nodes before linearized reading' (duration: 215.097483ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:52:19.504042Z","caller":"traceutil/trace.go:171","msg":"trace[1081116227] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:912; }","duration":"423.918347ms","start":"2026-02-10T18:52:19.080106Z","end":"2026-02-10T18:52:19.504025Z","steps":["trace[1081116227] 'agreement among raft nodes before linearized reading' (duration: 423.834065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:19.504458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.502588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-02-10T18:52:19.504453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:19.080092Z","time spent":"424.349347ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T18:52:19.504478Z","caller":"traceutil/trace.go:171","msg":"trace[1975551310] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:912; }","duration":"399.54275ms","start":"2026-02-10T18:52:19.10493Z","end":"2026-02-10T18:52:19.504472Z","steps":["trace[1975551310] 'agreement among raft nodes before linearized reading' (duration: 399.495839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:19.504499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:19.104916Z","time spent":"399.57748ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-10T18:52:19.504414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.273202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-10T18:52:19.504728Z","caller":"traceutil/trace.go:171","msg":"trace[1314090024] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:912; }","duration":"309.608891ms","start":"2026-02-10T18:52:19.195112Z","end":"2026-02-10T18:52:19.504721Z","steps":["trace[1314090024] 'agreement among raft nodes before linearized reading' (duration: 309.260372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:19.504753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:19.195102Z","time spent":"309.643881ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-10T18:52:20.012056Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255048295549407,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T18:52:20.187399Z","caller":"traceutil/trace.go:171","msg":"trace[1000022410] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"676.680201ms","start":"2026-02-10T18:52:19.510701Z","end":"2026-02-10T18:52:20.187382Z","steps":["trace[1000022410] 'process raft request' (duration: 676.586669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:20.187533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:19.510684Z","time spent":"676.791653ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:52:20.666116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.376349ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:52:20.666214Z","caller":"traceutil/trace.go:171","msg":"trace[1062264640] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:953; }","duration":"1.154991265s","start":"2026-02-10T18:52:19.511212Z","end":"2026-02-10T18:52:20.666204Z","steps":["trace[1062264640] 'read index received' (duration: 676.264921ms)","trace[1062264640] 'applied index is now lower than readState.Index' (duration: 478.725824ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:52:20.66642Z","caller":"traceutil/trace.go:171","msg":"trace[1738188538] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"1.155290282s","start":"2026-02-10T18:52:19.511123Z","end":"2026-02-10T18:52:20.666413Z","steps":["trace[1738188538] 'process raft request' (duration: 950.565845ms)","trace[1738188538] 'compare' (duration: 204.274477ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:52:20.666451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:19.511113Z","time spent":"1.155321583s","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:52:20.666543Z","caller":"traceutil/trace.go:171","msg":"trace[961502990] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"1.154583025s","start":"2026-02-10T18:52:19.511956Z","end":"2026-02-10T18:52:20.666539Z","steps":["trace[961502990] 'process raft request' (duration: 1.154219617s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:20.666566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:19.511944Z","time spent":"1.154608196s","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:52:20.666634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.155422685s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:52:20.666648Z","caller":"traceutil/trace.go:171","msg":"trace[856549337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:915; }","duration":"1.155434746s","start":"2026-02-10T18:52:19.511208Z","end":"2026-02-10T18:52:20.666643Z","steps":["trace[856549337] 'agreement among raft nodes before linearized reading' (duration: 1.155411025s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:20.666661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:19.511176Z","time spent":"1.155482067s","remote":"127.0.0.1:33462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:52:20.666782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.911491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-10T18:52:20.666795Z","caller":"traceutil/trace.go:171","msg":"trace[536238964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:915; }","duration":"631.950402ms","start":"2026-02-10T18:52:20.034841Z","end":"2026-02-10T18:52:20.666791Z","steps":["trace[536238964] 'agreement among raft nodes before linearized reading' (duration: 631.896101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:20.666806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:20.034826Z","time spent":"631.977792ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T18:52:21.961716Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255048295549419,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:52:22.461801Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255048295549419,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:52:22.574126Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.042273746s","expected-duration":"1s"} {"level":"info","ts":"2026-02-10T18:52:22.574756Z","caller":"traceutil/trace.go:171","msg":"trace[1853191548] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"1.823361679s","start":"2026-02-10T18:52:20.751358Z","end":"2026-02-10T18:52:22.574719Z","steps":["trace[1853191548] 'process raft request' (duration: 1.823236896s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:22.574861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:20.751347Z","time spent":"1.823449841s","remote":"127.0.0.1:33478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:52:22.681489Z","caller":"traceutil/trace.go:171","msg":"trace[1616232668] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:958; }","duration":"1.220657329s","start":"2026-02-10T18:52:21.460813Z","end":"2026-02-10T18:52:22.681471Z","steps":["trace[1616232668] 'read index received' (duration: 1.114184459s)","trace[1616232668] 'applied index is now lower than readState.Index' (duration: 106.47215ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:52:22.681565Z","caller":"traceutil/trace.go:171","msg":"trace[1515011814] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"958.191806ms","start":"2026-02-10T18:52:21.723366Z","end":"2026-02-10T18:52:22.681558Z","steps":["trace[1515011814] 'process raft request' (duration: 943.816265ms)","trace[1515011814] 'compare' (duration: 14.221497ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:52:22.68164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:21.723352Z","time spent":"958.229686ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:52:22.681673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.172713164s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-10T18:52:22.681694Z","caller":"traceutil/trace.go:171","msg":"trace[891895239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:918; }","duration":"1.172766856s","start":"2026-02-10T18:52:21.508921Z","end":"2026-02-10T18:52:22.681688Z","steps":["trace[891895239] 'agreement among raft nodes before linearized reading' (duration: 1.172704584s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:22.681707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:21.508881Z","time spent":"1.172823597s","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T18:52:22.681751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.220937906s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T18:52:22.681762Z","caller":"traceutil/trace.go:171","msg":"trace[822059524] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:918; }","duration":"1.220985857s","start":"2026-02-10T18:52:21.460773Z","end":"2026-02-10T18:52:22.681759Z","steps":["trace[822059524] 'agreement among raft nodes before linearized reading' (duration: 1.220963687s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:22.681773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:21.46075Z","time spent":"1.221020948s","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-02-10T18:52:22.681935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.589739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-10T18:52:22.68195Z","caller":"traceutil/trace.go:171","msg":"trace[602466776] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:918; }","duration":"105.621021ms","start":"2026-02-10T18:52:22.576325Z","end":"2026-02-10T18:52:22.681946Z","steps":["trace[602466776] 'agreement among raft nodes before linearized reading' (duration: 105.59439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:22.682033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.754749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-10T18:52:22.682046Z","caller":"traceutil/trace.go:171","msg":"trace[1243788381] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:918; }","duration":"490.78853ms","start":"2026-02-10T18:52:22.191253Z","end":"2026-02-10T18:52:22.682041Z","steps":["trace[1243788381] 'agreement among raft nodes before linearized reading' (duration: 490.755889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:22.682057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:22.191229Z","time spent":"490.82462ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-10T18:52:22.682128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.127438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:52:22.682151Z","caller":"traceutil/trace.go:171","msg":"trace[729663717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:918; }","duration":"646.174948ms","start":"2026-02-10T18:52:22.035969Z","end":"2026-02-10T18:52:22.682144Z","steps":["trace[729663717] 'agreement among raft nodes before linearized reading' (duration: 646.140348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:22.682169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:22.035955Z","time spent":"646.21015ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:52:29.327476Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255048295549465,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:52:29.827986Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255048295549465,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:52:29.834741Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.076050165s","expected-duration":"1s"} {"level":"info","ts":"2026-02-10T18:52:29.834927Z","caller":"traceutil/trace.go:171","msg":"trace[1177260551] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:976; }","duration":"1.007782549s","start":"2026-02-10T18:52:28.82713Z","end":"2026-02-10T18:52:29.834912Z","steps":["trace[1177260551] 'read index received' (duration: 1.007660286s)","trace[1177260551] 'applied index is now lower than readState.Index' (duration: 121.823µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:52:29.835103Z","caller":"traceutil/trace.go:171","msg":"trace[935462721] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"1.076451525s","start":"2026-02-10T18:52:28.758646Z","end":"2026-02-10T18:52:29.835097Z","steps":["trace[935462721] 'process raft request' (duration: 1.076178938s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:29.835162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:28.758637Z","time spent":"1.076479825s","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:52:29.835299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.008167338s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-10T18:52:29.835315Z","caller":"traceutil/trace.go:171","msg":"trace[1370785700] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:935; }","duration":"1.008200839s","start":"2026-02-10T18:52:28.827109Z","end":"2026-02-10T18:52:29.83531Z","steps":["trace[1370785700] 'agreement among raft nodes before linearized reading' (duration: 1.008140697s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:29.835328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:28.8271Z","time spent":"1.00822496s","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-10T18:52:29.835406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.502358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-10T18:52:29.835419Z","caller":"traceutil/trace.go:171","msg":"trace[239778750] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:935; }","duration":"886.535488ms","start":"2026-02-10T18:52:28.948879Z","end":"2026-02-10T18:52:29.835414Z","steps":["trace[239778750] 'agreement among raft nodes before linearized reading' (duration: 886.500847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:29.83543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:28.948869Z","time spent":"886.55876ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-10T18:52:29.835528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.644695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:52:29.835543Z","caller":"traceutil/trace.go:171","msg":"trace[1380852993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:935; }","duration":"800.687146ms","start":"2026-02-10T18:52:29.034852Z","end":"2026-02-10T18:52:29.835539Z","steps":["trace[1380852993] 'agreement among raft nodes before linearized reading' (duration: 800.664076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:29.835554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:29.034842Z","time spent":"800.709047ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:52:29.835631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.177188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-10T18:52:29.835642Z","caller":"traceutil/trace.go:171","msg":"trace[886237305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:935; }","duration":"831.188209ms","start":"2026-02-10T18:52:29.004451Z","end":"2026-02-10T18:52:29.835639Z","steps":["trace[886237305] 'agreement among raft nodes before linearized reading' (duration: 831.152068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:29.835654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:29.004436Z","time spent":"831.214289ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T18:52:29.835729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.891415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-10T18:52:29.835741Z","caller":"traceutil/trace.go:171","msg":"trace[211683539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:935; }","duration":"831.923526ms","start":"2026-02-10T18:52:29.003814Z","end":"2026-02-10T18:52:29.835737Z","steps":["trace[211683539] 'agreement among raft nodes before linearized reading' (duration: 831.889825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:29.835751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:29.003804Z","time spent":"831.944546ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T18:52:30.551592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.083975ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:52:30.551727Z","caller":"traceutil/trace.go:171","msg":"trace[1665965729] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:977; }","duration":"515.068165ms","start":"2026-02-10T18:52:30.03665Z","end":"2026-02-10T18:52:30.551718Z","steps":["trace[1665965729] 'read index received' (duration: 67.818606ms)","trace[1665965729] 'applied index is now lower than readState.Index' (duration: 447.248959ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:52:30.55177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.119076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:52:30.551781Z","caller":"traceutil/trace.go:171","msg":"trace[446438063] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:939; }","duration":"515.155066ms","start":"2026-02-10T18:52:30.036622Z","end":"2026-02-10T18:52:30.551777Z","steps":["trace[446438063] 'agreement among raft nodes before linearized reading' (duration: 515.120646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:30.551797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:30.036608Z","time spent":"515.184868ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T18:52:30.551919Z","caller":"traceutil/trace.go:171","msg":"trace[2042110682] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"713.107784ms","start":"2026-02-10T18:52:29.838806Z","end":"2026-02-10T18:52:30.551913Z","steps":["trace[2042110682] 'process raft request' (duration: 265.6579ms)","trace[2042110682] 'compare' (duration: 446.935142ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:52:30.551945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:29.838797Z","time spent":"713.134024ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:52:30.551994Z","caller":"traceutil/trace.go:171","msg":"trace[1402568562] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"711.956447ms","start":"2026-02-10T18:52:29.840034Z","end":"2026-02-10T18:52:30.551991Z","steps":["trace[1402568562] 'process raft request' (duration: 711.621379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:30.552015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:29.840028Z","time spent":"711.975437ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:52:30.552061Z","caller":"traceutil/trace.go:171","msg":"trace[566232875] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"710.792758ms","start":"2026-02-10T18:52:29.841265Z","end":"2026-02-10T18:52:30.552058Z","steps":["trace[566232875] 'process raft request' (duration: 710.420219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:30.552083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:29.841259Z","time spent":"710.811049ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:52:30.552146Z","caller":"traceutil/trace.go:171","msg":"trace[2096989899] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"710.409039ms","start":"2026-02-10T18:52:29.841733Z","end":"2026-02-10T18:52:30.552142Z","steps":["trace[2096989899] 'process raft request' (duration: 709.969929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:30.552167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:29.841727Z","time spent":"710.427799ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:52:31.712317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.029505245s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.12\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-10T18:52:31.71238Z","caller":"traceutil/trace.go:171","msg":"trace[505233359] range","detail":"{range_begin:/registry/masterleases/162.253.55.12; range_end:; response_count:1; response_revision:939; }","duration":"1.029619047s","start":"2026-02-10T18:52:30.682749Z","end":"2026-02-10T18:52:31.712368Z","steps":["trace[505233359] 'range keys from in-memory index tree' (duration: 1.029365501s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:31.712409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:30.682731Z","time spent":"1.029669848s","remote":"127.0.0.1:33478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.12\" "} {"level":"warn","ts":"2026-02-10T18:52:31.712468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.632554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:52:31.712516Z","caller":"traceutil/trace.go:171","msg":"trace[1202981297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:939; }","duration":"677.687665ms","start":"2026-02-10T18:52:31.034816Z","end":"2026-02-10T18:52:31.712503Z","steps":["trace[1202981297] 'range keys from in-memory index tree' (duration: 677.554562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:31.71254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:31.034776Z","time spent":"677.759207ms","remote":"127.0.0.1:33462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:52:32.172819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.127444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:52:32.173255Z","caller":"traceutil/trace.go:171","msg":"trace[2106386951] linearizableReadLoop","detail":"{readStateIndex:984; appliedIndex:982; }","duration":"137.423303ms","start":"2026-02-10T18:52:32.035815Z","end":"2026-02-10T18:52:32.173238Z","steps":["trace[2106386951] 'read index received' (duration: 136.776748ms)","trace[2106386951] 'applied index is now lower than readState.Index' (duration: 642.855µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:52:32.173445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.585087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:52:32.173545Z","caller":"traceutil/trace.go:171","msg":"trace[564404153] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:941; }","duration":"137.762641ms","start":"2026-02-10T18:52:32.035765Z","end":"2026-02-10T18:52:32.173528Z","steps":["trace[564404153] 'agreement among raft nodes before linearized reading' (duration: 137.573326ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:52:32.173594Z","caller":"traceutil/trace.go:171","msg":"trace[152959083] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"272.678896ms","start":"2026-02-10T18:52:31.900905Z","end":"2026-02-10T18:52:32.173584Z","steps":["trace[152959083] 'process raft request' (duration: 272.183414ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:52:32.17353Z","caller":"traceutil/trace.go:171","msg":"trace[2001487146] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"275.672016ms","start":"2026-02-10T18:52:31.897831Z","end":"2026-02-10T18:52:32.173503Z","steps":["trace[2001487146] 'process raft request' (duration: 29.770714ms)","trace[2001487146] 'compare' (duration: 244.913019ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:52:32.833484Z","caller":"traceutil/trace.go:171","msg":"trace[246697668] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"269.099961ms","start":"2026-02-10T18:52:32.564368Z","end":"2026-02-10T18:52:32.833468Z","steps":["trace[246697668] 'process raft request' (duration: 268.988458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:33.483328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.047788ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:52:33.48358Z","caller":"traceutil/trace.go:171","msg":"trace[236232881] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"501.527064ms","start":"2026-02-10T18:52:32.982036Z","end":"2026-02-10T18:52:33.483564Z","steps":["trace[236232881] 'process raft request' (duration: 501.465052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:33.48365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:32.982009Z","time spent":"501.605656ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:52:33.483725Z","caller":"traceutil/trace.go:171","msg":"trace[1750853547] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"918.134816ms","start":"2026-02-10T18:52:32.565568Z","end":"2026-02-10T18:52:33.483703Z","steps":["trace[1750853547] 'process raft request' (duration: 771.644758ms)","trace[1750853547] 'compare' (duration: 145.926825ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:52:33.483831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:32.565562Z","time spent":"918.210488ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:52:33.483844Z","caller":"traceutil/trace.go:171","msg":"trace[237302066] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"917.316987ms","start":"2026-02-10T18:52:32.566514Z","end":"2026-02-10T18:52:33.483831Z","steps":["trace[237302066] 'process raft request' (duration: 916.893447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:33.483897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:32.566508Z","time spent":"917.359938ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:52:33.483979Z","caller":"traceutil/trace.go:171","msg":"trace[1832834387] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:984; }","duration":"917.309496ms","start":"2026-02-10T18:52:32.566661Z","end":"2026-02-10T18:52:33.48397Z","steps":["trace[1832834387] 'read index received' (duration: 266.719374ms)","trace[1832834387] 'applied index is now lower than readState.Index' (duration: 650.588992ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:52:33.484118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.15261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:52:33.484143Z","caller":"traceutil/trace.go:171","msg":"trace[257725732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:945; }","duration":"448.202511ms","start":"2026-02-10T18:52:33.035933Z","end":"2026-02-10T18:52:33.484136Z","steps":["trace[257725732] 'agreement among raft nodes before linearized reading' (duration: 448.164951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:33.484163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:33.035919Z","time spent":"448.241062ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:52:33.484304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.650575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-10T18:52:33.484322Z","caller":"traceutil/trace.go:171","msg":"trace[1304172797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:945; }","duration":"917.696346ms","start":"2026-02-10T18:52:32.56662Z","end":"2026-02-10T18:52:33.484316Z","steps":["trace[1304172797] 'agreement among raft nodes before linearized reading' (duration: 917.623694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:33.484337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:32.566603Z","time spent":"917.731177ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T18:52:34.296487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.588625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:52:34.296556Z","caller":"traceutil/trace.go:171","msg":"trace[850891651] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:988; }","duration":"259.719199ms","start":"2026-02-10T18:52:34.036823Z","end":"2026-02-10T18:52:34.296542Z","steps":["trace[850891651] 'read index received' (duration: 53.691µs)","trace[850891651] 'applied index is now lower than readState.Index' (duration: 259.664738ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:52:34.296703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.896054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:52:34.296722Z","caller":"traceutil/trace.go:171","msg":"trace[1678644827] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:946; }","duration":"259.974325ms","start":"2026-02-10T18:52:34.036742Z","end":"2026-02-10T18:52:34.296716Z","steps":["trace[1678644827] 'agreement among raft nodes before linearized reading' (duration: 259.943334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:34.296725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.191241ms","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-02-10T18:52:34.296754Z","caller":"traceutil/trace.go:171","msg":"trace[1113187818] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:946; }","duration":"203.262363ms","start":"2026-02-10T18:52:34.093484Z","end":"2026-02-10T18:52:34.296746Z","steps":["trace[1113187818] 'agreement among raft nodes before linearized reading' (duration: 203.198511ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:52:34.296846Z","caller":"traceutil/trace.go:171","msg":"trace[801176536] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"758.545639ms","start":"2026-02-10T18:52:33.538293Z","end":"2026-02-10T18:52:34.296838Z","steps":["trace[801176536] 'process raft request' (duration: 199.552725ms)","trace[801176536] 'compare' (duration: 558.295047ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:52:34.296881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:33.538275Z","time spent":"758.58624ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:52:34.296918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.93475ms","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-02-10T18:52:34.296944Z","caller":"traceutil/trace.go:171","msg":"trace[436390592] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:946; }","duration":"111.988581ms","start":"2026-02-10T18:52:34.184943Z","end":"2026-02-10T18:52:34.296932Z","steps":["trace[436390592] 'agreement among raft nodes before linearized reading' (duration: 111.912559ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:52:35.319224Z","caller":"traceutil/trace.go:171","msg":"trace[728353982] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:989; }","duration":"481.563641ms","start":"2026-02-10T18:52:34.837634Z","end":"2026-02-10T18:52:35.319198Z","steps":["trace[728353982] 'read index received' (duration: 458.920095ms)","trace[728353982] 'applied index is now lower than readState.Index' (duration: 22.642906ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:52:35.319389Z","caller":"traceutil/trace.go:171","msg":"trace[1503236799] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"1.016484366s","start":"2026-02-10T18:52:34.302897Z","end":"2026-02-10T18:52:35.319382Z","steps":["trace[1503236799] 'process raft request' (duration: 993.712597ms)","trace[1503236799] 'compare' (duration: 22.278027ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:52:35.319483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:34.302879Z","time spent":"1.016525537s","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:52:35.319642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.006531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-10T18:52:35.319661Z","caller":"traceutil/trace.go:171","msg":"trace[2070565537] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:947; }","duration":"482.077064ms","start":"2026-02-10T18:52:34.837577Z","end":"2026-02-10T18:52:35.319654Z","steps":["trace[2070565537] 'agreement among raft nodes before linearized reading' (duration: 481.999342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:35.319675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:34.837559Z","time spent":"482.113404ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-10T18:52:35.319741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.145044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:52:35.319755Z","caller":"traceutil/trace.go:171","msg":"trace[37929112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:947; }","duration":"283.203595ms","start":"2026-02-10T18:52:35.036546Z","end":"2026-02-10T18:52:35.319749Z","steps":["trace[37929112] 'agreement among raft nodes before linearized reading' (duration: 283.184454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:36.296557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.957412ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-10T18:52:36.296636Z","caller":"traceutil/trace.go:171","msg":"trace[1397925090] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:994; }","duration":"260.764653ms","start":"2026-02-10T18:52:36.035859Z","end":"2026-02-10T18:52:36.296624Z","steps":["trace[1397925090] 'read index received' (duration: 25.674508ms)","trace[1397925090] 'applied index is now lower than readState.Index' (duration: 235.088805ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:52:36.296693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.829824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:52:36.29671Z","caller":"traceutil/trace.go:171","msg":"trace[1513841640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:951; }","duration":"260.876416ms","start":"2026-02-10T18:52:36.035827Z","end":"2026-02-10T18:52:36.296704Z","steps":["trace[1513841640] 'agreement among raft nodes before linearized reading' (duration: 260.826585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:37.915625Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255048295549516,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T18:52:37.940362Z","caller":"traceutil/trace.go:171","msg":"trace[419240457] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:996; }","duration":"525.172574ms","start":"2026-02-10T18:52:37.415169Z","end":"2026-02-10T18:52:37.940341Z","steps":["trace[419240457] 'read index received' (duration: 525.01439ms)","trace[419240457] 'applied index is now lower than readState.Index' (duration: 157.394µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:52:37.940648Z","caller":"traceutil/trace.go:171","msg":"trace[924168919] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"613.815953ms","start":"2026-02-10T18:52:37.326821Z","end":"2026-02-10T18:52:37.940637Z","steps":["trace[924168919] 'process raft request' (duration: 613.411104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:37.940748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:37.326802Z","time spent":"613.882824ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:52:37.940998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.82842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-10T18:52:37.941036Z","caller":"traceutil/trace.go:171","msg":"trace[1200608460] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:953; }","duration":"525.89306ms","start":"2026-02-10T18:52:37.415133Z","end":"2026-02-10T18:52:37.941026Z","steps":["trace[1200608460] 'agreement among raft nodes before linearized reading' (duration: 525.781098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:37.94107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:37.415119Z","time spent":"525.944692ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-10T18:52:37.941232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.61101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-10T18:52:37.941268Z","caller":"traceutil/trace.go:171","msg":"trace[1999295884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:953; }","duration":"407.670891ms","start":"2026-02-10T18:52:37.533589Z","end":"2026-02-10T18:52:37.94126Z","steps":["trace[1999295884] 'agreement among raft nodes before linearized reading' (duration: 407.592109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:37.941298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:37.533572Z","time spent":"407.720493ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T18:52:37.941584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.736303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-10T18:52:37.941629Z","caller":"traceutil/trace.go:171","msg":"trace[1337344072] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:953; }","duration":"388.782245ms","start":"2026-02-10T18:52:37.552837Z","end":"2026-02-10T18:52:37.941619Z","steps":["trace[1337344072] 'agreement among raft nodes before linearized reading' (duration: 388.679082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:37.941665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:37.552807Z","time spent":"388.850496ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-10T18:52:38.244259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.086991ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:52:38.244451Z","caller":"traceutil/trace.go:171","msg":"trace[1742793288] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:997; }","duration":"209.587592ms","start":"2026-02-10T18:52:38.034849Z","end":"2026-02-10T18:52:38.244436Z","steps":["trace[1742793288] 'read index received' (duration: 106.262966ms)","trace[1742793288] 'applied index is now lower than readState.Index' (duration: 103.323786ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:52:38.244538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.685134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:52:38.246391Z","caller":"traceutil/trace.go:171","msg":"trace[417580964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:956; }","duration":"211.557339ms","start":"2026-02-10T18:52:38.03482Z","end":"2026-02-10T18:52:38.246377Z","steps":["trace[417580964] 'agreement among raft nodes before linearized reading' (duration: 209.661264ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:52:38.245118Z","caller":"traceutil/trace.go:171","msg":"trace[581905409] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"296.940831ms","start":"2026-02-10T18:52:37.948158Z","end":"2026-02-10T18:52:38.245099Z","steps":["trace[581905409] 'process raft request' (duration: 192.933168ms)","trace[581905409] 'compare' (duration: 102.733823ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:52:38.245414Z","caller":"traceutil/trace.go:171","msg":"trace[365775150] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"295.957967ms","start":"2026-02-10T18:52:37.949445Z","end":"2026-02-10T18:52:38.245403Z","steps":["trace[365775150] 'process raft request' (duration: 294.894002ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:52:38.245454Z","caller":"traceutil/trace.go:171","msg":"trace[446334411] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"295.955217ms","start":"2026-02-10T18:52:37.949492Z","end":"2026-02-10T18:52:38.245447Z","steps":["trace[446334411] 'process raft request' (duration: 294.915282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:38.686889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.601508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-10T18:52:38.686944Z","caller":"traceutil/trace.go:171","msg":"trace[2034222885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:956; }","duration":"314.68858ms","start":"2026-02-10T18:52:38.372238Z","end":"2026-02-10T18:52:38.686927Z","steps":["trace[2034222885] 'range keys from in-memory index tree' (duration: 314.478115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:38.68697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:38.372221Z","time spent":"314.743302ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T18:52:39.13976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.82257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:52:39.139885Z","caller":"traceutil/trace.go:171","msg":"trace[1030654360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:957; }","duration":"101.994745ms","start":"2026-02-10T18:52:39.037871Z","end":"2026-02-10T18:52:39.139865Z","steps":["trace[1030654360] 'range keys from in-memory index tree' (duration: 101.755909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:45.232449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.306713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:52:45.232602Z","caller":"traceutil/trace.go:171","msg":"trace[541989000] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1030; }","duration":"485.072754ms","start":"2026-02-10T18:52:44.747428Z","end":"2026-02-10T18:52:45.232501Z","steps":["trace[541989000] 'read index received' (duration: 66.081µs)","trace[541989000] 'applied index is now lower than readState.Index' (duration: 485.005653ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:52:45.232919Z","caller":"traceutil/trace.go:171","msg":"trace[1850783008] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"946.287954ms","start":"2026-02-10T18:52:44.286523Z","end":"2026-02-10T18:52:45.232811Z","steps":["trace[1850783008] 'process raft request' (duration: 73.365817ms)","trace[1850783008] 'compare' (duration: 871.821601ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:52:45.232955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:44.28651Z","time spent":"946.425798ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:52:45.233384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.954786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-10T18:52:45.233401Z","caller":"traceutil/trace.go:171","msg":"trace[1687516198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:986; }","duration":"486.007956ms","start":"2026-02-10T18:52:44.747388Z","end":"2026-02-10T18:52:45.233396Z","steps":["trace[1687516198] 'agreement among raft nodes before linearized reading' (duration: 485.841242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:45.233415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:44.74737Z","time spent":"486.042688ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T18:52:45.233389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.159668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:52:45.23345Z","caller":"traceutil/trace.go:171","msg":"trace[1821564768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:986; }","duration":"197.25209ms","start":"2026-02-10T18:52:45.036185Z","end":"2026-02-10T18:52:45.233437Z","steps":["trace[1821564768] 'agreement among raft nodes before linearized reading' (duration: 197.160028ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:52:51.429459Z","caller":"traceutil/trace.go:171","msg":"trace[85914969] linearizableReadLoop","detail":"{readStateIndex:1064; appliedIndex:1063; }","duration":"110.582298ms","start":"2026-02-10T18:52:51.318859Z","end":"2026-02-10T18:52:51.429441Z","steps":["trace[85914969] 'read index received' (duration: 110.431695ms)","trace[85914969] 'applied index is now lower than readState.Index' (duration: 149.953µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:52:51.429635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.759502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-10T18:52:51.429657Z","caller":"traceutil/trace.go:171","msg":"trace[797685608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1017; }","duration":"110.833554ms","start":"2026-02-10T18:52:51.318816Z","end":"2026-02-10T18:52:51.429649Z","steps":["trace[797685608] 'agreement among raft nodes before linearized reading' (duration: 110.699141ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:52:51.429761Z","caller":"traceutil/trace.go:171","msg":"trace[807071831] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"138.493619ms","start":"2026-02-10T18:52:51.291244Z","end":"2026-02-10T18:52:51.429737Z","steps":["trace[807071831] 'process raft request' (duration: 138.08897ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:52:51.537009Z","caller":"traceutil/trace.go:171","msg":"trace[1088233451] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"104.042844ms","start":"2026-02-10T18:52:51.43295Z","end":"2026-02-10T18:52:51.536993Z","steps":["trace[1088233451] 'process raft request' (duration: 101.75294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:52.580045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.755843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:52:52.580843Z","caller":"traceutil/trace.go:171","msg":"trace[209793571] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1019; }","duration":"374.887336ms","start":"2026-02-10T18:52:52.205217Z","end":"2026-02-10T18:52:52.580104Z","steps":["trace[209793571] 'count revisions from in-memory index tree' (duration: 374.662471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:52.58093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:52.2052Z","time spent":"375.701626ms","remote":"127.0.0.1:33578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-02-10T18:52:52.580987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.400491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-10T18:52:52.58106Z","caller":"traceutil/trace.go:171","msg":"trace[403382598] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1019; }","duration":"161.617307ms","start":"2026-02-10T18:52:52.419425Z","end":"2026-02-10T18:52:52.581042Z","steps":["trace[403382598] 'range keys from in-memory index tree' (duration: 160.650934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:52.581588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.400739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-10T18:52:52.581627Z","caller":"traceutil/trace.go:171","msg":"trace[405480371] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1019; }","duration":"152.47073ms","start":"2026-02-10T18:52:52.429144Z","end":"2026-02-10T18:52:52.581615Z","steps":["trace[405480371] 'range keys from in-memory index tree' (duration: 152.243215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:57.228671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.173107ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:52:57.228807Z","caller":"traceutil/trace.go:171","msg":"trace[744376794] linearizableReadLoop","detail":"{readStateIndex:1091; appliedIndex:1090; }","duration":"320.233042ms","start":"2026-02-10T18:52:56.90856Z","end":"2026-02-10T18:52:57.228793Z","steps":["trace[744376794] 'read index received' (duration: 106.811109ms)","trace[744376794] 'applied index is now lower than readState.Index' (duration: 213.420713ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:52:57.228882Z","caller":"traceutil/trace.go:171","msg":"trace[244539441] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"486.527889ms","start":"2026-02-10T18:52:56.742337Z","end":"2026-02-10T18:52:57.228865Z","steps":["trace[244539441] 'process raft request' (duration: 273.090095ms)","trace[244539441] 'store kv pair into bolt db' {req_type:put; key:/registry/events/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-v8spf.1892f75bb3cdf07f; req_size:787; } (duration: 213.032314ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:52:57.228923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:56.742319Z","time spent":"486.585831ms","remote":"127.0.0.1:33508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T18:52:57.357678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.089792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:52:57.357786Z","caller":"traceutil/trace.go:171","msg":"trace[2101961408] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1043; }","duration":"449.197185ms","start":"2026-02-10T18:52:56.908534Z","end":"2026-02-10T18:52:57.357731Z","steps":["trace[2101961408] 'agreement among raft nodes before linearized reading' (duration: 320.54372ms)","trace[2101961408] 'range keys from in-memory index tree' (duration: 128.547513ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:52:57.357854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:56.908522Z","time spent":"449.317038ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-10T18:52:57.357804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.431595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:52:57.358063Z","caller":"traceutil/trace.go:171","msg":"trace[378545295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1043; }","duration":"320.720553ms","start":"2026-02-10T18:52:57.037331Z","end":"2026-02-10T18:52:57.358052Z","steps":["trace[378545295] 'agreement among raft nodes before linearized reading' (duration: 191.76448ms)","trace[378545295] 'range keys from in-memory index tree' (duration: 128.689246ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:52:57.358098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:57.037315Z","time spent":"320.774734ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T18:53:02.557047Z","caller":"traceutil/trace.go:171","msg":"trace[1441703769] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"110.959457ms","start":"2026-02-10T18:53:02.446067Z","end":"2026-02-10T18:53:02.557027Z","steps":["trace[1441703769] 'process raft request' (duration: 90.342359ms)","trace[1441703769] 'compare' (duration: 20.25071ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:53:02.733656Z","caller":"traceutil/trace.go:171","msg":"trace[1221280462] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"108.497079ms","start":"2026-02-10T18:53:02.625137Z","end":"2026-02-10T18:53:02.733634Z","steps":["trace[1221280462] 'process raft request' (duration: 57.976443ms)","trace[1221280462] 'compare' (duration: 50.29641ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:53:03.892932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.682694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:53:03.893605Z","caller":"traceutil/trace.go:171","msg":"trace[1101314948] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"205.789591ms","start":"2026-02-10T18:53:03.687786Z","end":"2026-02-10T18:53:03.893575Z","steps":["trace[1101314948] 'process raft request' (duration: 82.39084ms)","trace[1101314948] 'compare' (duration: 122.286915ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:53:03.893792Z","caller":"traceutil/trace.go:171","msg":"trace[330941882] linearizableReadLoop","detail":"{readStateIndex:1140; appliedIndex:1139; }","duration":"110.148658ms","start":"2026-02-10T18:53:03.782856Z","end":"2026-02-10T18:53:03.893005Z","steps":["trace[330941882] 'read index received' (duration: 30.621µs)","trace[330941882] 'applied index is now lower than readState.Index' (duration: 110.117027ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:53:03.893928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.058259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T18:53:03.893989Z","caller":"traceutil/trace.go:171","msg":"trace[725606596] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1090; }","duration":"111.154052ms","start":"2026-02-10T18:53:03.782819Z","end":"2026-02-10T18:53:03.893973Z","steps":["trace[725606596] 'agreement among raft nodes before linearized reading' (duration: 111.014638ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:53:07.101637Z","caller":"traceutil/trace.go:171","msg":"trace[942406853] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"122.850118ms","start":"2026-02-10T18:53:06.978762Z","end":"2026-02-10T18:53:07.101612Z","steps":["trace[942406853] 'process raft request' (duration: 122.314215ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:14.737447Z","caller":"traceutil/trace.go:171","msg":"trace[1616089087] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"107.728307ms","start":"2026-02-10T18:54:14.629695Z","end":"2026-02-10T18:54:14.737424Z","steps":["trace[1616089087] 'process raft request' (duration: 107.573584ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:22.928252Z","caller":"traceutil/trace.go:171","msg":"trace[1540862926] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"132.69349ms","start":"2026-02-10T18:54:22.795532Z","end":"2026-02-10T18:54:22.928225Z","steps":["trace[1540862926] 'process raft request' (duration: 54.219334ms)","trace[1540862926] 'compare' (duration: 78.384064ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:54:52.848061Z","caller":"traceutil/trace.go:171","msg":"trace[702119319] transaction","detail":"{read_only:false; response_revision:1533; number_of_response:1; }","duration":"162.546985ms","start":"2026-02-10T18:54:52.685481Z","end":"2026-02-10T18:54:52.848028Z","steps":["trace[702119319] 'process raft request' (duration: 69.716009ms)","trace[702119319] 'compare' (duration: 92.553139ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:54:53.855274Z","caller":"traceutil/trace.go:171","msg":"trace[58780490] transaction","detail":"{read_only:false; response_revision:1537; number_of_response:1; }","duration":"124.038465ms","start":"2026-02-10T18:54:53.73122Z","end":"2026-02-10T18:54:53.855258Z","steps":["trace[58780490] 'process raft request' (duration: 123.952923ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:54.019663Z","caller":"traceutil/trace.go:171","msg":"trace[1205747830] transaction","detail":"{read_only:false; response_revision:1539; number_of_response:1; }","duration":"287.310817ms","start":"2026-02-10T18:54:53.73233Z","end":"2026-02-10T18:54:54.019641Z","steps":["trace[1205747830] 'process raft request' (duration: 287.258655ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:54.020104Z","caller":"traceutil/trace.go:171","msg":"trace[74021976] transaction","detail":"{read_only:false; response_revision:1538; number_of_response:1; }","duration":"288.72963ms","start":"2026-02-10T18:54:53.73136Z","end":"2026-02-10T18:54:54.02009Z","steps":["trace[74021976] 'process raft request' (duration: 234.923987ms)","trace[74021976] 'compare' (duration: 53.160708ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:54:55.40501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.16009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T18:54:55.405059Z","caller":"traceutil/trace.go:171","msg":"trace[1811879486] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1543; }","duration":"174.244773ms","start":"2026-02-10T18:54:55.230804Z","end":"2026-02-10T18:54:55.405049Z","steps":["trace[1811879486] 'count revisions from in-memory index tree' (duration: 174.081299ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:01.347492Z","caller":"traceutil/trace.go:171","msg":"trace[1346980974] transaction","detail":"{read_only:false; response_revision:1568; number_of_response:1; }","duration":"108.971648ms","start":"2026-02-10T18:55:01.238472Z","end":"2026-02-10T18:55:01.347444Z","steps":["trace[1346980974] 'process raft request' (duration: 108.792474ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:20.358033Z","caller":"traceutil/trace.go:171","msg":"trace[191356197] transaction","detail":"{read_only:false; response_revision:1646; number_of_response:1; }","duration":"150.534751ms","start":"2026-02-10T18:55:20.207482Z","end":"2026-02-10T18:55:20.358016Z","steps":["trace[191356197] 'process raft request' (duration: 150.448259ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:34.854706Z","caller":"traceutil/trace.go:171","msg":"trace[550036172] transaction","detail":"{read_only:false; response_revision:1706; number_of_response:1; }","duration":"267.853016ms","start":"2026-02-10T18:55:34.586819Z","end":"2026-02-10T18:55:34.854672Z","steps":["trace[550036172] 'process raft request' (duration: 267.707123ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:39.182075Z","caller":"traceutil/trace.go:171","msg":"trace[877910983] transaction","detail":"{read_only:false; response_revision:1722; number_of_response:1; }","duration":"101.620517ms","start":"2026-02-10T18:55:39.080425Z","end":"2026-02-10T18:55:39.182046Z","steps":["trace[877910983] 'process raft request' (duration: 100.969502ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:39.465571Z","caller":"traceutil/trace.go:171","msg":"trace[1374619103] transaction","detail":"{read_only:false; response_revision:1723; number_of_response:1; }","duration":"278.244579ms","start":"2026-02-10T18:55:39.187285Z","end":"2026-02-10T18:55:39.465529Z","steps":["trace[1374619103] 'process raft request' (duration: 278.040905ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:39.469393Z","caller":"traceutil/trace.go:171","msg":"trace[1167991213] linearizableReadLoop","detail":"{readStateIndex:1804; appliedIndex:1804; }","duration":"239.505293ms","start":"2026-02-10T18:55:39.229859Z","end":"2026-02-10T18:55:39.469364Z","steps":["trace[1167991213] 'read index received' (duration: 239.495483ms)","trace[1167991213] 'applied index is now lower than readState.Index' (duration: 7.79µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:55:39.501962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.086846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-10T18:55:39.502055Z","caller":"traceutil/trace.go:171","msg":"trace[716211304] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1723; }","duration":"272.223528ms","start":"2026-02-10T18:55:39.229808Z","end":"2026-02-10T18:55:39.502031Z","steps":["trace[716211304] 'agreement among raft nodes before linearized reading' (duration: 239.671817ms)","trace[716211304] 'range keys from in-memory index tree' (duration: 32.295866ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:55:45.920472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.035521ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-10T18:55:48.203788Z","caller":"traceutil/trace.go:171","msg":"trace[1692287100] transaction","detail":"{read_only:false; response_revision:1758; number_of_response:1; }","duration":"117.400327ms","start":"2026-02-10T18:55:48.086365Z","end":"2026-02-10T18:55:48.203766Z","steps":["trace[1692287100] 'process raft request' (duration: 117.357066ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:48.203827Z","caller":"traceutil/trace.go:171","msg":"trace[2104694071] transaction","detail":"{read_only:false; response_revision:1757; number_of_response:1; }","duration":"199.60473ms","start":"2026-02-10T18:55:48.004179Z","end":"2026-02-10T18:55:48.203783Z","steps":["trace[2104694071] 'process raft request' (duration: 100.256635ms)","trace[2104694071] 'compare' (duration: 99.171821ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:55:48.204368Z","caller":"traceutil/trace.go:171","msg":"trace[560058957] linearizableReadLoop","detail":"{readStateIndex:1840; appliedIndex:1839; }","duration":"126.651304ms","start":"2026-02-10T18:55:48.077702Z","end":"2026-02-10T18:55:48.204354Z","steps":["trace[560058957] 'read index received' (duration: 26.742066ms)","trace[560058957] 'applied index is now lower than readState.Index' (duration: 99.907568ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:55:48.204656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.93555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-02-10T18:55:48.204701Z","caller":"traceutil/trace.go:171","msg":"trace[1273821571] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1758; }","duration":"127.032812ms","start":"2026-02-10T18:55:48.077655Z","end":"2026-02-10T18:55:48.204688Z","steps":["trace[1273821571] 'agreement among raft nodes before linearized reading' (duration: 126.766146ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:50.991862Z","caller":"traceutil/trace.go:171","msg":"trace[1172899591] transaction","detail":"{read_only:false; response_revision:1769; number_of_response:1; }","duration":"162.329658ms","start":"2026-02-10T18:55:50.829509Z","end":"2026-02-10T18:55:50.991839Z","steps":["trace[1172899591] 'process raft request' (duration: 162.193914ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:51.016657Z","caller":"traceutil/trace.go:171","msg":"trace[1505968286] transaction","detail":"{read_only:false; response_revision:1770; number_of_response:1; }","duration":"185.448028ms","start":"2026-02-10T18:55:50.831187Z","end":"2026-02-10T18:55:51.016635Z","steps":["trace[1505968286] 'process raft request' (duration: 184.21637ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:51.175331Z","caller":"traceutil/trace.go:171","msg":"trace[414479170] transaction","detail":"{read_only:false; response_revision:1771; number_of_response:1; }","duration":"124.503332ms","start":"2026-02-10T18:55:51.050799Z","end":"2026-02-10T18:55:51.175303Z","steps":["trace[414479170] 'process raft request' (duration: 71.656676ms)","trace[414479170] 'compare' (duration: 51.823862ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:55:51.433558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.635832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-10T18:55:51.434165Z","caller":"traceutil/trace.go:171","msg":"trace[604252502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1772; }","duration":"109.271907ms","start":"2026-02-10T18:55:51.324874Z","end":"2026-02-10T18:55:51.434146Z","steps":["trace[604252502] 'range keys from in-memory index tree' (duration: 108.498569ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:52.569695Z","caller":"traceutil/trace.go:171","msg":"trace[716746162] transaction","detail":"{read_only:false; response_revision:1776; number_of_response:1; }","duration":"119.582077ms","start":"2026-02-10T18:55:52.45009Z","end":"2026-02-10T18:55:52.569672Z","steps":["trace[716746162] 'process raft request' (duration: 119.481045ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:56:07.409281Z","caller":"traceutil/trace.go:171","msg":"trace[1213350652] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"164.558019ms","start":"2026-02-10T18:56:07.244699Z","end":"2026-02-10T18:56:07.409257Z","steps":["trace[1213350652] 'process raft request' (duration: 98.411402ms)","trace[1213350652] 'compare' (duration: 65.650825ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:56:12.352907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.966252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:56:12.352974Z","caller":"traceutil/trace.go:171","msg":"trace[517658779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1881; }","duration":"319.066334ms","start":"2026-02-10T18:56:12.033895Z","end":"2026-02-10T18:56:12.352962Z","steps":["trace[517658779] 'range keys from in-memory index tree' (duration: 318.88295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:56:12.353001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:56:12.033862Z","time spent":"319.133916ms","remote":"127.0.0.1:33462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T18:56:13.82879Z","caller":"traceutil/trace.go:171","msg":"trace[1964051646] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"148.401362ms","start":"2026-02-10T18:56:13.680364Z","end":"2026-02-10T18:56:13.828766Z","steps":["trace[1964051646] 'process raft request' (duration: 148.261759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:56:14.900714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014083794s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-10T18:56:14.900792Z","caller":"traceutil/trace.go:171","msg":"trace[1501316950] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1888; }","duration":"1.014207567s","start":"2026-02-10T18:56:13.886566Z","end":"2026-02-10T18:56:14.900774Z","steps":["trace[1501316950] 'range keys from in-memory index tree' (duration: 1.013255765s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:56:14.900833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:56:13.886546Z","time spent":"1.014278548s","remote":"127.0.0.1:33726","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-02-10T18:56:14.90147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.107858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-10T18:56:14.90278Z","caller":"traceutil/trace.go:171","msg":"trace[1034079631] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1888; }","duration":"350.45097ms","start":"2026-02-10T18:56:14.55231Z","end":"2026-02-10T18:56:14.902761Z","steps":["trace[1034079631] 'range keys from in-memory index tree' (duration: 348.972675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:56:14.902913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:56:14.552291Z","time spent":"350.605243ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-10T18:56:14.903258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.372309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:56:14.903299Z","caller":"traceutil/trace.go:171","msg":"trace[851138025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1888; }","duration":"866.620744ms","start":"2026-02-10T18:56:14.036666Z","end":"2026-02-10T18:56:14.903287Z","steps":["trace[851138025] 'range keys from in-memory index tree' (duration: 866.321207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:56:14.903341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:56:14.03665Z","time spent":"866.680676ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:56:14.903965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.357942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-10T18:56:14.90402Z","caller":"traceutil/trace.go:171","msg":"trace[564494039] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1888; }","duration":"263.449364ms","start":"2026-02-10T18:56:14.640555Z","end":"2026-02-10T18:56:14.904004Z","steps":["trace[564494039] 'range keys from in-memory index tree' (duration: 263.220738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:56:14.904239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.807777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-10T18:56:14.904282Z","caller":"traceutil/trace.go:171","msg":"trace[315988992] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1888; }","duration":"179.886059ms","start":"2026-02-10T18:56:14.724385Z","end":"2026-02-10T18:56:14.904271Z","steps":["trace[315988992] 'range keys from in-memory index tree' (duration: 179.694745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:56:14.907755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.528886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-10T18:56:14.907779Z","caller":"traceutil/trace.go:171","msg":"trace[2038583594] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1888; }","duration":"157.601548ms","start":"2026-02-10T18:56:14.750172Z","end":"2026-02-10T18:56:14.907774Z","steps":["trace[2038583594] 'range keys from in-memory index tree' (duration: 157.440734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:56:14.907828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.103082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T18:56:14.907842Z","caller":"traceutil/trace.go:171","msg":"trace[860382163] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1888; }","duration":"147.137642ms","start":"2026-02-10T18:56:14.760698Z","end":"2026-02-10T18:56:14.907836Z","steps":["trace[860382163] 'count revisions from in-memory index tree' (duration: 147.060761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:56:20.19644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.824936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:56:20.196581Z","caller":"traceutil/trace.go:171","msg":"trace[1087363200] linearizableReadLoop","detail":"{readStateIndex:2006; appliedIndex:2004; }","duration":"671.07741ms","start":"2026-02-10T18:56:19.525493Z","end":"2026-02-10T18:56:20.19657Z","steps":["trace[1087363200] 'read index received' (duration: 73.08239ms)","trace[1087363200] 'applied index is now lower than readState.Index' (duration: 597.99419ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:56:20.196639Z","caller":"traceutil/trace.go:171","msg":"trace[171171429] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"673.320083ms","start":"2026-02-10T18:56:19.523311Z","end":"2026-02-10T18:56:20.196631Z","steps":["trace[171171429] 'process raft request' (duration: 75.252001ms)","trace[171171429] 'compare' (duration: 597.434387ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:56:20.196672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:56:19.523292Z","time spent":"673.362523ms","remote":"127.0.0.1:33508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":706,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T18:56:20.196879Z","caller":"traceutil/trace.go:171","msg":"trace[1839424562] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"671.50548ms","start":"2026-02-10T18:56:19.525363Z","end":"2026-02-10T18:56:20.196869Z","steps":["trace[1839424562] 'process raft request' (duration: 671.154342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:56:20.196919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:56:19.525345Z","time spent":"671.549091ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:56:20.197053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.558201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2833"} {"level":"info","ts":"2026-02-10T18:56:20.197072Z","caller":"traceutil/trace.go:171","msg":"trace[1128553890] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1916; }","duration":"671.594812ms","start":"2026-02-10T18:56:19.525471Z","end":"2026-02-10T18:56:20.197066Z","steps":["trace[1128553890] 'agreement among raft nodes before linearized reading' (duration: 671.52072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:56:20.197089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:56:19.525464Z","time spent":"671.621633ms","remote":"127.0.0.1:33624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":2856,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"warn","ts":"2026-02-10T18:56:20.197183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.927886ms","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":"info","ts":"2026-02-10T18:56:20.197201Z","caller":"traceutil/trace.go:171","msg":"trace[331509321] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1916; }","duration":"499.973087ms","start":"2026-02-10T18:56:19.697222Z","end":"2026-02-10T18:56:20.197195Z","steps":["trace[331509321] 'agreement among raft nodes before linearized reading' (duration: 499.942726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:56:20.197218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:56:19.697207Z","time spent":"500.006378ms","remote":"127.0.0.1:37534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-02-10T18:56:20.197427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.541989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-10T18:56:20.197446Z","caller":"traceutil/trace.go:171","msg":"trace[1572285118] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1916; }","duration":"264.58207ms","start":"2026-02-10T18:56:19.932858Z","end":"2026-02-10T18:56:20.19744Z","steps":["trace[1572285118] 'agreement among raft nodes before linearized reading' (duration: 264.505718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:56:20.197555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.149903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-10T18:56:20.197573Z","caller":"traceutil/trace.go:171","msg":"trace[513607476] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1916; }","duration":"265.168114ms","start":"2026-02-10T18:56:19.9324Z","end":"2026-02-10T18:56:20.197568Z","steps":["trace[513607476] 'agreement among raft nodes before linearized reading' (duration: 265.118562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:56:20.197672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.434202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-10T18:56:20.197687Z","caller":"traceutil/trace.go:171","msg":"trace[1898459765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1916; }","duration":"348.483923ms","start":"2026-02-10T18:56:19.849199Z","end":"2026-02-10T18:56:20.197683Z","steps":["trace[1898459765] 'agreement among raft nodes before linearized reading' (duration: 348.438082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:56:20.197705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:56:19.849181Z","time spent":"348.519984ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-10T18:56:20.33374Z","caller":"traceutil/trace.go:171","msg":"trace[387461208] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"124.581395ms","start":"2026-02-10T18:56:20.209142Z","end":"2026-02-10T18:56:20.333724Z","steps":["trace[387461208] 'process raft request' (duration: 122.292381ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:56:20.334022Z","caller":"traceutil/trace.go:171","msg":"trace[1740329190] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"124.123814ms","start":"2026-02-10T18:56:20.20989Z","end":"2026-02-10T18:56:20.334014Z","steps":["trace[1740329190] 'process raft request' (duration: 123.602121ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:56:45.570169Z","caller":"traceutil/trace.go:171","msg":"trace[1689278973] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"118.411696ms","start":"2026-02-10T18:56:45.45173Z","end":"2026-02-10T18:56:45.570142Z","steps":["trace[1689278973] 'process raft request' (duration: 27.33955ms)","trace[1689278973] 'compare' (duration: 90.800389ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:56:59.752563Z","caller":"traceutil/trace.go:171","msg":"trace[1097408546] transaction","detail":"{read_only:false; response_revision:2086; number_of_response:1; }","duration":"119.785528ms","start":"2026-02-10T18:56:59.632758Z","end":"2026-02-10T18:56:59.752543Z","steps":["trace[1097408546] 'process raft request' (duration: 119.458871ms)"],"step_count":1}