{"level":"warn","ts":"2026-01-30T16:23:03.014432Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-30T16:23:03.014554Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.70: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.70:2380","--initial-cluster=instance=https://162.253.55.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.70: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-01-30T16:23:03.014641Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-30T16:23:03.014656Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-01-30T16:23:03.014685Z","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-01-30T16:23:03.015522Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"]} {"level":"info","ts":"2026-01-30T16:23:03.015671Z","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.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.70: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-01-30T16:23:03.021239Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.067046ms"} {"level":"info","ts":"2026-01-30T16:23:03.044569Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"23887246632736aa","cluster-id":"62cfeca3ef46d3ef"} {"level":"info","ts":"2026-01-30T16:23:03.044992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=()"} {"level":"info","ts":"2026-01-30T16:23:03.04507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 0"} {"level":"info","ts":"2026-01-30T16:23:03.04513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 23887246632736aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-30T16:23:03.045203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 1"} {"level":"info","ts":"2026-01-30T16:23:03.045292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"warn","ts":"2026-01-30T16:23:03.060037Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-30T16:23:03.067593Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-30T16:23:03.068773Z","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-01-30T16:23:03.070639Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"23887246632736aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-30T16:23:03.084823Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"23887246632736aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-30T16:23:03.085083Z","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-01-30T16:23:03.087575Z","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-01-30T16:23:03.087605Z","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-01-30T16:23:03.087508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"info","ts":"2026-01-30T16:23:03.092339Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","added-peer-id":"23887246632736aa","added-peer-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-01-30T16:23:03.101303Z","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-01-30T16:23:03.101508Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"23887246632736aa","initial-advertise-peer-urls":["https://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-30T16:23:03.10153Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-30T16:23:03.101608Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-01-30T16:23:03.101617Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-01-30T16:23:03.146393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa is starting a new election at term 1"} {"level":"info","ts":"2026-01-30T16:23:03.146535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became pre-candidate at term 1"} {"level":"info","ts":"2026-01-30T16:23:03.146625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgPreVoteResp from 23887246632736aa at term 1"} {"level":"info","ts":"2026-01-30T16:23:03.146661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became candidate at term 2"} {"level":"info","ts":"2026-01-30T16:23:03.146697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgVoteResp from 23887246632736aa at term 2"} {"level":"info","ts":"2026-01-30T16:23:03.146769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became leader at term 2"} {"level":"info","ts":"2026-01-30T16:23:03.146793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 23887246632736aa elected leader 23887246632736aa at term 2"} {"level":"info","ts":"2026-01-30T16:23:03.148349Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T16:23:03.148626Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"23887246632736aa","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.70:2379]}","request-path":"/0/members/23887246632736aa/attributes","cluster-id":"62cfeca3ef46d3ef","publish-timeout":"7s"} {"level":"info","ts":"2026-01-30T16:23:03.150417Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-30T16:23:03.150585Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T16:23:03.150738Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T16:23:03.150795Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T16:23:03.150823Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-30T16:23:03.151103Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-30T16:23:03.151139Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-30T16:23:03.153771Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.70:2379"} {"level":"info","ts":"2026-01-30T16:23:03.15594Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-30T16:23:33.970858Z","caller":"traceutil/trace.go:171","msg":"trace[1941403616] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:522; }","duration":"326.840132ms","start":"2026-01-30T16:23:33.643999Z","end":"2026-01-30T16:23:33.970839Z","steps":["trace[1941403616] 'read index received' (duration: 326.658918ms)","trace[1941403616] 'applied index is now lower than readState.Index' (duration: 180.454µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:23:33.970943Z","caller":"traceutil/trace.go:171","msg":"trace[340914533] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"688.239587ms","start":"2026-01-30T16:23:33.282698Z","end":"2026-01-30T16:23:33.970937Z","steps":["trace[340914533] 'process raft request' (duration: 688.012381ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:23:33.971213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.256473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-30T16:23:33.971305Z","caller":"traceutil/trace.go:171","msg":"trace[1772410110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:509; }","duration":"322.378957ms","start":"2026-01-30T16:23:33.648912Z","end":"2026-01-30T16:23:33.971291Z","steps":["trace[1772410110] 'agreement among raft nodes before linearized reading' (duration: 322.246054ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:23:33.97132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:23:33.282687Z","time spent":"688.274197ms","remote":"127.0.0.1:49184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4253,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:23:33.971345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:23:33.648902Z","time spent":"322.434918ms","remote":"127.0.0.1:49252","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-01-30T16:23:33.971503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.5053ms","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-01-30T16:23:33.971522Z","caller":"traceutil/trace.go:171","msg":"trace[1730620881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:509; }","duration":"327.561832ms","start":"2026-01-30T16:23:33.643955Z","end":"2026-01-30T16:23:33.971517Z","steps":["trace[1730620881] 'agreement among raft nodes before linearized reading' (duration: 327.525981ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:23:33.971539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:23:33.643934Z","time spent":"327.601592ms","remote":"127.0.0.1:49252","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-01-30T16:23:33.971621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.185328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:23:33.971649Z","caller":"traceutil/trace.go:171","msg":"trace[1951894934] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"239.26054ms","start":"2026-01-30T16:23:33.732379Z","end":"2026-01-30T16:23:33.97164Z","steps":["trace[1951894934] 'agreement among raft nodes before linearized reading' (duration: 239.219519ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:23:33.971801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.377449ms","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-01-30T16:23:33.971839Z","caller":"traceutil/trace.go:171","msg":"trace[403288652] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:509; }","duration":"171.450521ms","start":"2026-01-30T16:23:33.800382Z","end":"2026-01-30T16:23:33.971832Z","steps":["trace[403288652] 'agreement among raft nodes before linearized reading' (duration: 171.394959ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:23:33.971872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.706919ms","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-01-30T16:23:33.971894Z","caller":"traceutil/trace.go:171","msg":"trace[2010680375] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:509; }","duration":"112.7589ms","start":"2026-01-30T16:23:33.859129Z","end":"2026-01-30T16:23:33.971888Z","steps":["trace[2010680375] 'agreement among raft nodes before linearized reading' (duration: 112.711109ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:23:36.755044Z","caller":"traceutil/trace.go:171","msg":"trace[1140961287] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"586.34043ms","start":"2026-01-30T16:23:36.168687Z","end":"2026-01-30T16:23:36.755027Z","steps":["trace[1140961287] 'process raft request' (duration: 586.245617ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:23:36.755141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:23:36.168671Z","time spent":"586.417831ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:23:37.688902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.364394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-30T16:23:37.688962Z","caller":"traceutil/trace.go:171","msg":"trace[580398265] range","detail":"{range_begin:/registry/masterleases/162.253.55.70; range_end:; response_count:1; response_revision:518; }","duration":"748.469347ms","start":"2026-01-30T16:23:36.940479Z","end":"2026-01-30T16:23:37.688949Z","steps":["trace[580398265] 'range keys from in-memory index tree' (duration: 748.297733ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:23:37.688997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:23:36.940467Z","time spent":"748.514419ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.70\" "} {"level":"info","ts":"2026-01-30T16:23:37.899978Z","caller":"traceutil/trace.go:171","msg":"trace[597909098] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"125.120894ms","start":"2026-01-30T16:23:37.774838Z","end":"2026-01-30T16:23:37.899959Z","steps":["trace[597909098] 'process raft request' (duration: 122.511956ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:23:39.055608Z","caller":"traceutil/trace.go:171","msg":"trace[580264995] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"236.856717ms","start":"2026-01-30T16:23:38.81873Z","end":"2026-01-30T16:23:39.055586Z","steps":["trace[580264995] 'process raft request' (duration: 236.747953ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:23:43.918624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.882863669s","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-01-30T16:23:43.918696Z","caller":"traceutil/trace.go:171","msg":"trace[874036386] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:531; }","duration":"1.882965022s","start":"2026-01-30T16:23:42.035712Z","end":"2026-01-30T16:23:43.918677Z","steps":["trace[874036386] 'range keys from in-memory index tree' (duration: 1.882757226s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:23:43.918733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:23:42.035697Z","time spent":"1.883028504s","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-30T16:23:43.919025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.879260805s","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-01-30T16:23:43.9191Z","caller":"traceutil/trace.go:171","msg":"trace[701557752] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:531; }","duration":"1.879354787s","start":"2026-01-30T16:23:42.039725Z","end":"2026-01-30T16:23:43.919079Z","steps":["trace[701557752] 'range keys from in-memory index tree' (duration: 1.879198284s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:23:43.919123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.843325678s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-01-30T16:23:43.919138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:23:42.039718Z","time spent":"1.879412439s","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-01-30T16:23:43.919185Z","caller":"traceutil/trace.go:171","msg":"trace[1801665885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:531; }","duration":"1.843380289s","start":"2026-01-30T16:23:42.075768Z","end":"2026-01-30T16:23:43.919148Z","steps":["trace[1801665885] 'range keys from in-memory index tree' (duration: 1.843267816s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:23:43.919216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:23:42.075758Z","time spent":"1.843451021s","remote":"127.0.0.1:49252","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-01-30T16:23:43.9193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.851397119s","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-01-30T16:23:43.919325Z","caller":"traceutil/trace.go:171","msg":"trace[2032997053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:531; }","duration":"1.851469151s","start":"2026-01-30T16:23:42.067847Z","end":"2026-01-30T16:23:43.919316Z","steps":["trace[2032997053] 'range keys from in-memory index tree' (duration: 1.851328117s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:23:43.919353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:23:42.067828Z","time spent":"1.851519232s","remote":"127.0.0.1:49252","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-01-30T16:23:44.025293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.039274071s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-01-30T16:23:44.025485Z","caller":"traceutil/trace.go:171","msg":"trace[695502760] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:546; }","duration":"1.238044244s","start":"2026-01-30T16:23:42.787429Z","end":"2026-01-30T16:23:44.025473Z","steps":["trace[695502760] 'read index received' (duration: 198.450355ms)","trace[695502760] 'applied index is now lower than readState.Index' (duration: 1.039592899s)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:23:44.025609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.238172367s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6bh9w\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-01-30T16:23:44.025628Z","caller":"traceutil/trace.go:171","msg":"trace[1080403428] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6bh9w; range_end:; response_count:1; response_revision:531; }","duration":"1.238239189s","start":"2026-01-30T16:23:42.787382Z","end":"2026-01-30T16:23:44.025621Z","steps":["trace[1080403428] 'agreement among raft nodes before linearized reading' (duration: 1.238149216s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:23:44.025649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:23:42.787346Z","time spent":"1.23829865s","remote":"127.0.0.1:49188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16674,"request content":"key:\"/registry/pods/kube-system/cilium-6bh9w\" "} {"level":"warn","ts":"2026-01-30T16:23:44.026943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.197005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:23:44.026967Z","caller":"traceutil/trace.go:171","msg":"trace[1032683369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"298.244147ms","start":"2026-01-30T16:23:43.728716Z","end":"2026-01-30T16:23:44.02696Z","steps":["trace[1032683369] 'agreement among raft nodes before linearized reading' (duration: 298.197916ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:23:44.027155Z","caller":"traceutil/trace.go:171","msg":"trace[725424316] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"100.411859ms","start":"2026-01-30T16:23:43.926735Z","end":"2026-01-30T16:23:44.027147Z","steps":["trace[725424316] 'process raft request' (duration: 100.107461ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:23:44.027308Z","caller":"traceutil/trace.go:171","msg":"trace[2029118148] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"101.658641ms","start":"2026-01-30T16:23:43.925643Z","end":"2026-01-30T16:23:44.027302Z","steps":["trace[2029118148] 'process raft request' (duration: 99.725821ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:23:44.028899Z","caller":"traceutil/trace.go:171","msg":"trace[2085335907] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"100.535422ms","start":"2026-01-30T16:23:43.928355Z","end":"2026-01-30T16:23:44.028891Z","steps":["trace[2085335907] 'process raft request' (duration: 98.540139ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:23:44.028992Z","caller":"traceutil/trace.go:171","msg":"trace[1828059505] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"100.688966ms","start":"2026-01-30T16:23:43.928297Z","end":"2026-01-30T16:23:44.028986Z","steps":["trace[1828059505] 'process raft request' (duration: 98.579631ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:23:46.606549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.515056ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:23:46.60675Z","caller":"traceutil/trace.go:171","msg":"trace[1430058873] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:563; }","duration":"512.452093ms","start":"2026-01-30T16:23:46.094284Z","end":"2026-01-30T16:23:46.606736Z","steps":["trace[1430058873] 'read index received' (duration: 346.721831ms)","trace[1430058873] 'applied index is now lower than readState.Index' (duration: 165.729442ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:23:46.606931Z","caller":"traceutil/trace.go:171","msg":"trace[1679204484] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"513.384546ms","start":"2026-01-30T16:23:46.093533Z","end":"2026-01-30T16:23:46.606918Z","steps":["trace[1679204484] 'process raft request' (duration: 347.43561ms)","trace[1679204484] 'compare' (duration: 165.258009ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:23:46.607002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:23:46.093518Z","time spent":"513.438468ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:23:46.607184Z","caller":"traceutil/trace.go:171","msg":"trace[902968728] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"513.239643ms","start":"2026-01-30T16:23:46.093909Z","end":"2026-01-30T16:23:46.607148Z","steps":["trace[902968728] 'process raft request' (duration: 512.72042ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:23:46.607245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:23:46.093897Z","time spent":"513.317755ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:23:46.607484Z","caller":"traceutil/trace.go:171","msg":"trace[3116730] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"505.647035ms","start":"2026-01-30T16:23:46.101825Z","end":"2026-01-30T16:23:46.607472Z","steps":["trace[3116730] 'process raft request' (duration: 504.850074ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:23:46.607586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:23:46.101817Z","time spent":"505.735577ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:23:46.607721Z","caller":"traceutil/trace.go:171","msg":"trace[1939152940] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"505.84498ms","start":"2026-01-30T16:23:46.101863Z","end":"2026-01-30T16:23:46.607708Z","steps":["trace[1939152940] 'process raft request' (duration: 504.842614ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:23:46.60777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:23:46.101859Z","time spent":"505.886031ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:23:46.60794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.650393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:23:46.608053Z","caller":"traceutil/trace.go:171","msg":"trace[1440112294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"513.782556ms","start":"2026-01-30T16:23:46.09426Z","end":"2026-01-30T16:23:46.608043Z","steps":["trace[1440112294] 'agreement among raft nodes before linearized reading' (duration: 513.652233ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:23:46.608127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:23:46.094252Z","time spent":"513.86561ms","remote":"127.0.0.1:48980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-30T16:23:46.608279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.156698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:23:46.608305Z","caller":"traceutil/trace.go:171","msg":"trace[1524914336] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:551; }","duration":"496.20508ms","start":"2026-01-30T16:23:46.112092Z","end":"2026-01-30T16:23:46.608297Z","steps":["trace[1524914336] 'agreement among raft nodes before linearized reading' (duration: 496.166429ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:23:46.608335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:23:46.112079Z","time spent":"496.250461ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-01-30T16:23:54.908374Z","caller":"traceutil/trace.go:171","msg":"trace[1141900070] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"105.09554ms","start":"2026-01-30T16:23:54.803265Z","end":"2026-01-30T16:23:54.908361Z","steps":["trace[1141900070] 'process raft request' (duration: 43.032141ms)","trace[1141900070] 'compare' (duration: 61.72901ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:23:54.908466Z","caller":"traceutil/trace.go:171","msg":"trace[1914875566] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"105.139261ms","start":"2026-01-30T16:23:54.803322Z","end":"2026-01-30T16:23:54.908461Z","steps":["trace[1914875566] 'process raft request' (duration: 104.796882ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:23:54.908512Z","caller":"traceutil/trace.go:171","msg":"trace[1283574030] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"104.684369ms","start":"2026-01-30T16:23:54.803824Z","end":"2026-01-30T16:23:54.908509Z","steps":["trace[1283574030] 'process raft request' (duration: 104.31573ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:23:55.646387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.207484ms","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-01-30T16:23:55.647173Z","caller":"traceutil/trace.go:171","msg":"trace[1972785289] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:650; }","duration":"534.004325ms","start":"2026-01-30T16:23:55.113157Z","end":"2026-01-30T16:23:55.647161Z","steps":["trace[1972785289] 'range keys from in-memory index tree' (duration: 533.181204ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:23:55.648599Z","caller":"traceutil/trace.go:171","msg":"trace[1616017631] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"436.051261ms","start":"2026-01-30T16:23:55.212541Z","end":"2026-01-30T16:23:55.648592Z","steps":["trace[1616017631] 'process raft request' (duration: 435.734273ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:23:55.648737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:23:55.212526Z","time spent":"436.166054ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1236,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-30T16:23:56.099184Z","caller":"traceutil/trace.go:171","msg":"trace[757351307] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"422.566719ms","start":"2026-01-30T16:23:55.676601Z","end":"2026-01-30T16:23:56.099167Z","steps":["trace[757351307] 'process raft request' (duration: 335.896169ms)","trace[757351307] 'compare' (duration: 86.34715ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:23:56.099474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:23:55.676577Z","time spent":"422.851706ms","remote":"127.0.0.1:49188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18231,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:23:56.214398Z","caller":"traceutil/trace.go:171","msg":"trace[1898577962] transaction","detail":"{read_only:false; number_of_response:0; response_revision:652; }","duration":"536.368086ms","start":"2026-01-30T16:23:55.677993Z","end":"2026-01-30T16:23:56.214361Z","steps":["trace[1898577962] 'process raft request' (duration: 534.745604ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:23:56.214496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:23:55.677987Z","time spent":"536.46537ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-30T16:23:56.214757Z","caller":"traceutil/trace.go:171","msg":"trace[877132181] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:671; }","duration":"485.178831ms","start":"2026-01-30T16:23:55.729559Z","end":"2026-01-30T16:23:56.214738Z","steps":["trace[877132181] 'read index received' (duration: 283.123333ms)","trace[877132181] 'applied index is now lower than readState.Index' (duration: 202.054548ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:23:56.214863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.291124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:23:56.214911Z","caller":"traceutil/trace.go:171","msg":"trace[1514833745] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"485.372136ms","start":"2026-01-30T16:23:55.729532Z","end":"2026-01-30T16:23:56.214904Z","steps":["trace[1514833745] 'agreement among raft nodes before linearized reading' (duration: 485.281774ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:23:56.21495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:23:55.72952Z","time spent":"485.424088ms","remote":"127.0.0.1:48980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-30T16:23:56.215176Z","caller":"traceutil/trace.go:171","msg":"trace[1149786002] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"532.167657ms","start":"2026-01-30T16:23:55.683Z","end":"2026-01-30T16:23:56.215168Z","steps":["trace[1149786002] 'process raft request' (duration: 531.310665ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:23:56.215243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:23:55.682989Z","time spent":"532.223278ms","remote":"127.0.0.1:44560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2261,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-30T16:23:58.995615Z","caller":"traceutil/trace.go:171","msg":"trace[1063285732] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"184.475839ms","start":"2026-01-30T16:23:58.811116Z","end":"2026-01-30T16:23:58.995592Z","steps":["trace[1063285732] 'process raft request' (duration: 184.332496ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:23:59.557051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.768044ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:23:59.557146Z","caller":"traceutil/trace.go:171","msg":"trace[376517742] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"557.467547ms","start":"2026-01-30T16:23:58.999656Z","end":"2026-01-30T16:23:59.557124Z","steps":["trace[376517742] 'process raft request' (duration: 223.537699ms)","trace[376517742] 'compare' (duration: 333.60523ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:23:59.557202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:23:58.999638Z","time spent":"557.537799ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:23:59.570708Z","caller":"traceutil/trace.go:171","msg":"trace[97569550] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"565.458036ms","start":"2026-01-30T16:23:59.005233Z","end":"2026-01-30T16:23:59.570691Z","steps":["trace[97569550] 'process raft request' (duration: 565.433115ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:23:59.570833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:23:59.005221Z","time spent":"565.538588ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:23:59.571401Z","caller":"traceutil/trace.go:171","msg":"trace[1813417966] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"569.273845ms","start":"2026-01-30T16:23:59.002118Z","end":"2026-01-30T16:23:59.571392Z","steps":["trace[1813417966] 'process raft request' (duration: 568.442094ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:23:59.57146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:23:59.002093Z","time spent":"569.342397ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:23:59.571622Z","caller":"traceutil/trace.go:171","msg":"trace[809546755] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"569.441689ms","start":"2026-01-30T16:23:59.002172Z","end":"2026-01-30T16:23:59.571614Z","steps":["trace[809546755] 'process raft request' (duration: 568.474274ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:23:59.571693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:23:59.002168Z","time spent":"569.502201ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:23:59.575151Z","caller":"traceutil/trace.go:171","msg":"trace[823958306] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"172.379145ms","start":"2026-01-30T16:23:59.402764Z","end":"2026-01-30T16:23:59.575144Z","steps":["trace[823958306] 'process raft request' (duration: 172.341564ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:24:00.923655Z","caller":"traceutil/trace.go:171","msg":"trace[674087019] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"859.986445ms","start":"2026-01-30T16:24:00.06365Z","end":"2026-01-30T16:24:00.923636Z","steps":["trace[674087019] 'process raft request' (duration: 859.867562ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:00.92378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:00.063627Z","time spent":"860.092208ms","remote":"127.0.0.1:49188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18043,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:24:01.180195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.862009ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:24:01.180295Z","caller":"traceutil/trace.go:171","msg":"trace[269648537] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"244.478185ms","start":"2026-01-30T16:24:00.935799Z","end":"2026-01-30T16:24:01.180277Z","steps":["trace[269648537] 'process raft request' (duration: 102.450712ms)","trace[269648537] 'compare' (duration: 141.629223ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:24:01.219942Z","caller":"traceutil/trace.go:171","msg":"trace[770369279] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:691; }","duration":"219.881263ms","start":"2026-01-30T16:24:01.000023Z","end":"2026-01-30T16:24:01.219904Z","steps":["trace[770369279] 'read index received' (duration: 38.303719ms)","trace[770369279] 'applied index is now lower than readState.Index' (duration: 181.575874ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:24:01.219966Z","caller":"traceutil/trace.go:171","msg":"trace[625673721] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"284.113818ms","start":"2026-01-30T16:24:00.93582Z","end":"2026-01-30T16:24:01.219934Z","steps":["trace[625673721] 'process raft request' (duration: 283.80918ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:01.220249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.187952ms","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-01-30T16:24:01.220289Z","caller":"traceutil/trace.go:171","msg":"trace[1008533060] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:672; }","duration":"220.287665ms","start":"2026-01-30T16:24:00.999988Z","end":"2026-01-30T16:24:01.220276Z","steps":["trace[1008533060] 'agreement among raft nodes before linearized reading' (duration: 220.049688ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:24:01.220883Z","caller":"traceutil/trace.go:171","msg":"trace[1920024545] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"284.53884ms","start":"2026-01-30T16:24:00.936328Z","end":"2026-01-30T16:24:01.220867Z","steps":["trace[1920024545] 'process raft request' (duration: 283.481462ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:24:01.511032Z","caller":"traceutil/trace.go:171","msg":"trace[188789867] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"319.159543ms","start":"2026-01-30T16:24:01.191825Z","end":"2026-01-30T16:24:01.510985Z","steps":["trace[188789867] 'process raft request' (duration: 317.183732ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:01.511567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:01.191809Z","time spent":"319.332237ms","remote":"127.0.0.1:49086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-30T16:24:01.518563Z","caller":"traceutil/trace.go:171","msg":"trace[1856592384] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"286.224163ms","start":"2026-01-30T16:24:01.232311Z","end":"2026-01-30T16:24:01.518535Z","steps":["trace[1856592384] 'process raft request' (duration: 286.049988ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:24:01.518679Z","caller":"traceutil/trace.go:171","msg":"trace[1366294927] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"284.314553ms","start":"2026-01-30T16:24:01.234358Z","end":"2026-01-30T16:24:01.518673Z","steps":["trace[1366294927] 'process raft request' (duration: 284.135139ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:24:09.689759Z","caller":"traceutil/trace.go:171","msg":"trace[1639550036] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"129.447792ms","start":"2026-01-30T16:24:09.560294Z","end":"2026-01-30T16:24:09.689742Z","steps":["trace[1639550036] 'process raft request' (duration: 129.309669ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:24:10.616801Z","caller":"traceutil/trace.go:171","msg":"trace[265435374] linearizableReadLoop","detail":"{readStateIndex:767; appliedIndex:765; }","duration":"987.238192ms","start":"2026-01-30T16:24:09.629546Z","end":"2026-01-30T16:24:10.616784Z","steps":["trace[265435374] 'read index received' (duration: 60.119683ms)","trace[265435374] 'applied index is now lower than readState.Index' (duration: 927.117879ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:24:10.616887Z","caller":"traceutil/trace.go:171","msg":"trace[717532218] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"1.037662824s","start":"2026-01-30T16:24:09.579219Z","end":"2026-01-30T16:24:10.616882Z","steps":["trace[717532218] 'process raft request' (duration: 1.037459228s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:10.616955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:09.579204Z","time spent":"1.037696174s","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:24:10.617079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.5379ms","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-01-30T16:24:10.617094Z","caller":"traceutil/trace.go:171","msg":"trace[1996361220] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:742; }","duration":"987.593291ms","start":"2026-01-30T16:24:09.629496Z","end":"2026-01-30T16:24:10.617089Z","steps":["trace[1996361220] 'agreement among raft nodes before linearized reading' (duration: 987.529499ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:10.617108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:09.629483Z","time spent":"987.620921ms","remote":"127.0.0.1:49252","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":"info","ts":"2026-01-30T16:24:10.84021Z","caller":"traceutil/trace.go:171","msg":"trace[759256480] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"338.795968ms","start":"2026-01-30T16:24:10.501399Z","end":"2026-01-30T16:24:10.840195Z","steps":["trace[759256480] 'process raft request' (duration: 338.691345ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:10.840329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:10.501373Z","time spent":"338.887911ms","remote":"127.0.0.1:49184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4768,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:24:10.840634Z","caller":"traceutil/trace.go:171","msg":"trace[375809041] linearizableReadLoop","detail":"{readStateIndex:768; appliedIndex:768; }","duration":"223.770008ms","start":"2026-01-30T16:24:10.616858Z","end":"2026-01-30T16:24:10.840628Z","steps":["trace[375809041] 'read index received' (duration: 223.766638ms)","trace[375809041] 'applied index is now lower than readState.Index' (duration: 2.91µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:24:10.840712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.137361747s","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-01-30T16:24:10.840733Z","caller":"traceutil/trace.go:171","msg":"trace[26729194] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:743; }","duration":"1.137405468s","start":"2026-01-30T16:24:09.70332Z","end":"2026-01-30T16:24:10.840726Z","steps":["trace[26729194] 'agreement among raft nodes before linearized reading' (duration: 1.137338447s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:10.840751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:09.703309Z","time spent":"1.137438489s","remote":"127.0.0.1:49252","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-01-30T16:24:10.84084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.099761247s","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-01-30T16:24:10.840858Z","caller":"traceutil/trace.go:171","msg":"trace[978354381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:743; }","duration":"1.099787288s","start":"2026-01-30T16:24:09.741066Z","end":"2026-01-30T16:24:10.840853Z","steps":["trace[978354381] 'agreement among raft nodes before linearized reading' (duration: 1.099746357s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:10.840876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:09.741061Z","time spent":"1.099811768s","remote":"127.0.0.1:49252","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-01-30T16:24:10.840987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1118967s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:24:10.841006Z","caller":"traceutil/trace.go:171","msg":"trace[1027135541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:743; }","duration":"1.111932511s","start":"2026-01-30T16:24:09.72907Z","end":"2026-01-30T16:24:10.841002Z","steps":["trace[1027135541] 'agreement among raft nodes before linearized reading' (duration: 1.1119033s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:10.841021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:09.729062Z","time spent":"1.111957222s","remote":"127.0.0.1:48980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-30T16:24:10.84111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.137737887s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-30T16:24:10.841126Z","caller":"traceutil/trace.go:171","msg":"trace[2095139940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:743; }","duration":"1.137763508s","start":"2026-01-30T16:24:09.70336Z","end":"2026-01-30T16:24:10.841123Z","steps":["trace[2095139940] 'agreement among raft nodes before linearized reading' (duration: 1.137724527s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:10.841141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:09.703357Z","time spent":"1.137781398s","remote":"127.0.0.1:49252","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-01-30T16:24:11.090206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.856217ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:24:11.090368Z","caller":"traceutil/trace.go:171","msg":"trace[1862729349] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"240.663104ms","start":"2026-01-30T16:24:10.849694Z","end":"2026-01-30T16:24:11.090357Z","steps":["trace[1862729349] 'process raft request' (duration: 240.639133ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:24:11.09062Z","caller":"traceutil/trace.go:171","msg":"trace[1519362789] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"470.714834ms","start":"2026-01-30T16:24:10.619899Z","end":"2026-01-30T16:24:11.090614Z","steps":["trace[1519362789] 'process raft request' (duration: 305.393975ms)","trace[1519362789] 'compare' (duration: 164.764874ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:24:11.090655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:10.619887Z","time spent":"470.749605ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:24:11.090732Z","caller":"traceutil/trace.go:171","msg":"trace[858979436] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:768; }","duration":"246.595087ms","start":"2026-01-30T16:24:10.844132Z","end":"2026-01-30T16:24:11.090727Z","steps":["trace[858979436] 'read index received' (duration: 81.213337ms)","trace[858979436] 'applied index is now lower than readState.Index' (duration: 165.38118ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:24:11.090774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.641629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:24:11.09079Z","caller":"traceutil/trace.go:171","msg":"trace[978200191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:747; }","duration":"246.6826ms","start":"2026-01-30T16:24:10.844104Z","end":"2026-01-30T16:24:11.090786Z","steps":["trace[978200191] 'agreement among raft nodes before linearized reading' (duration: 246.645359ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:24:11.090858Z","caller":"traceutil/trace.go:171","msg":"trace[63074535] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"243.681462ms","start":"2026-01-30T16:24:10.847173Z","end":"2026-01-30T16:24:11.090854Z","steps":["trace[63074535] 'process raft request' (duration: 243.097137ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:24:11.090924Z","caller":"traceutil/trace.go:171","msg":"trace[116990711] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"243.061776ms","start":"2026-01-30T16:24:10.847855Z","end":"2026-01-30T16:24:11.090917Z","steps":["trace[116990711] 'process raft request' (duration: 242.448821ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:24:27.138279Z","caller":"traceutil/trace.go:171","msg":"trace[395107847] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"166.291074ms","start":"2026-01-30T16:24:26.971961Z","end":"2026-01-30T16:24:27.138252Z","steps":["trace[395107847] 'process raft request' (duration: 80.487699ms)","trace[395107847] 'compare' (duration: 85.529028ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:24:27.860068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.615971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:24:27.861178Z","caller":"traceutil/trace.go:171","msg":"trace[163541372] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"129.758381ms","start":"2026-01-30T16:24:27.731396Z","end":"2026-01-30T16:24:27.861154Z","steps":["trace[163541372] 'range keys from in-memory index tree' (duration: 128.56798ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:24:28.161859Z","caller":"traceutil/trace.go:171","msg":"trace[1701262890] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:829; }","duration":"296.302861ms","start":"2026-01-30T16:24:27.86553Z","end":"2026-01-30T16:24:28.161833Z","steps":["trace[1701262890] 'read index received' (duration: 296.098746ms)","trace[1701262890] 'applied index is now lower than readState.Index' (duration: 202.965µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:24:28.162338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.458635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:24:28.162396Z","caller":"traceutil/trace.go:171","msg":"trace[1802726017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:799; }","duration":"296.865585ms","start":"2026-01-30T16:24:27.865513Z","end":"2026-01-30T16:24:28.162379Z","steps":["trace[1802726017] 'agreement among raft nodes before linearized reading' (duration: 296.428344ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:24:28.162981Z","caller":"traceutil/trace.go:171","msg":"trace[1973508905] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"297.655096ms","start":"2026-01-30T16:24:27.865309Z","end":"2026-01-30T16:24:28.162964Z","steps":["trace[1973508905] 'process raft request' (duration: 296.383433ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:28.902642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.132327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-30T16:24:28.902703Z","caller":"traceutil/trace.go:171","msg":"trace[1284378621] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:799; }","duration":"102.230459ms","start":"2026-01-30T16:24:28.800461Z","end":"2026-01-30T16:24:28.902691Z","steps":["trace[1284378621] 'count revisions from in-memory index tree' (duration: 102.082136ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:29.821777Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132415399070665,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-30T16:24:30.009183Z","caller":"traceutil/trace.go:171","msg":"trace[429319117] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:830; }","duration":"687.596244ms","start":"2026-01-30T16:24:29.321565Z","end":"2026-01-30T16:24:30.009162Z","steps":["trace[429319117] 'read index received' (duration: 687.4383ms)","trace[429319117] 'applied index is now lower than readState.Index' (duration: 156.974µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:24:30.00999Z","caller":"traceutil/trace.go:171","msg":"trace[2040130417] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"780.906594ms","start":"2026-01-30T16:24:29.229062Z","end":"2026-01-30T16:24:30.009968Z","steps":["trace[2040130417] 'process raft request' (duration: 779.959849ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:30.010181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:29.229042Z","time spent":"780.989296ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:24:30.010208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.5536ms","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-01-30T16:24:30.010263Z","caller":"traceutil/trace.go:171","msg":"trace[635905783] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:800; }","duration":"688.639781ms","start":"2026-01-30T16:24:29.32161Z","end":"2026-01-30T16:24:30.01025Z","steps":["trace[635905783] 'agreement among raft nodes before linearized reading' (duration: 688.463907ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:30.010295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:29.321602Z","time spent":"688.687482ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-30T16:24:30.010414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.851176ms","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-01-30T16:24:30.010432Z","caller":"traceutil/trace.go:171","msg":"trace[439127435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:800; }","duration":"688.904758ms","start":"2026-01-30T16:24:29.321522Z","end":"2026-01-30T16:24:30.010427Z","steps":["trace[439127435] 'agreement among raft nodes before linearized reading' (duration: 688.852926ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:30.010448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:29.321494Z","time spent":"688.950559ms","remote":"127.0.0.1:49252","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-01-30T16:24:30.010509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.860424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:24:30.010528Z","caller":"traceutil/trace.go:171","msg":"trace[1868251832] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:800; }","duration":"251.908035ms","start":"2026-01-30T16:24:29.758615Z","end":"2026-01-30T16:24:30.010523Z","steps":["trace[1868251832] 'agreement among raft nodes before linearized reading' (duration: 251.872404ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:30.0107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.578014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:24:30.010717Z","caller":"traceutil/trace.go:171","msg":"trace[313461605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:800; }","duration":"278.622455ms","start":"2026-01-30T16:24:29.732089Z","end":"2026-01-30T16:24:30.010712Z","steps":["trace[313461605] 'agreement among raft nodes before linearized reading' (duration: 278.597064ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:30.010857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.218496ms","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-01-30T16:24:30.010923Z","caller":"traceutil/trace.go:171","msg":"trace[661728361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:800; }","duration":"689.326059ms","start":"2026-01-30T16:24:29.321586Z","end":"2026-01-30T16:24:30.010912Z","steps":["trace[661728361] 'agreement among raft nodes before linearized reading' (duration: 689.176445ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:30.010961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:29.321563Z","time spent":"689.38502ms","remote":"127.0.0.1:49252","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-01-30T16:24:30.011074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.7923ms","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-01-30T16:24:30.011101Z","caller":"traceutil/trace.go:171","msg":"trace[1693754339] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:800; }","duration":"474.858562ms","start":"2026-01-30T16:24:29.536234Z","end":"2026-01-30T16:24:30.011092Z","steps":["trace[1693754339] 'agreement among raft nodes before linearized reading' (duration: 474.813261ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:30.011126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:29.536209Z","time spent":"474.910713ms","remote":"127.0.0.1:49462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":29,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-01-30T16:24:30.480091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.384308ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:24:30.480436Z","caller":"traceutil/trace.go:171","msg":"trace[774798203] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"454.246269ms","start":"2026-01-30T16:24:30.026171Z","end":"2026-01-30T16:24:30.480417Z","steps":["trace[774798203] 'process raft request' (duration: 454.208358ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:30.480582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:30.026161Z","time spent":"454.373324ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:24:30.480703Z","caller":"traceutil/trace.go:171","msg":"trace[211578132] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"458.022297ms","start":"2026-01-30T16:24:30.022658Z","end":"2026-01-30T16:24:30.480681Z","steps":["trace[211578132] 'process raft request' (duration: 116.966501ms)","trace[211578132] 'compare' (duration: 340.278506ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:24:30.480789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:30.022634Z","time spent":"458.114029ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:24:30.480905Z","caller":"traceutil/trace.go:171","msg":"trace[1342457471] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:831; }","duration":"458.184431ms","start":"2026-01-30T16:24:30.022707Z","end":"2026-01-30T16:24:30.480892Z","steps":["trace[1342457471] 'read index received' (duration: 116.931009ms)","trace[1342457471] 'applied index is now lower than readState.Index' (duration: 341.251762ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:24:30.481004Z","caller":"traceutil/trace.go:171","msg":"trace[154461869] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"457.688058ms","start":"2026-01-30T16:24:30.023306Z","end":"2026-01-30T16:24:30.480994Z","steps":["trace[154461869] 'process raft request' (duration: 457.02246ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:30.481087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:30.023296Z","time spent":"457.754251ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:24:30.481291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.093135ms","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-01-30T16:24:30.481332Z","caller":"traceutil/trace.go:171","msg":"trace[1827166916] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:803; }","duration":"313.180087ms","start":"2026-01-30T16:24:30.168142Z","end":"2026-01-30T16:24:30.481322Z","steps":["trace[1827166916] 'agreement among raft nodes before linearized reading' (duration: 313.064794ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:30.48136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:30.168125Z","time spent":"313.229169ms","remote":"127.0.0.1:49252","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-01-30T16:24:30.481457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.748486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:24:30.481486Z","caller":"traceutil/trace.go:171","msg":"trace[580512860] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:803; }","duration":"458.798287ms","start":"2026-01-30T16:24:30.022679Z","end":"2026-01-30T16:24:30.481477Z","steps":["trace[580512860] 'agreement among raft nodes before linearized reading' (duration: 458.757396ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:30.481508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:30.022664Z","time spent":"458.839498ms","remote":"127.0.0.1:48990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-30T16:24:41.279461Z","caller":"traceutil/trace.go:171","msg":"trace[1802875780] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"355.991002ms","start":"2026-01-30T16:24:40.923448Z","end":"2026-01-30T16:24:41.279439Z","steps":["trace[1802875780] 'process raft request' (duration: 355.864799ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:41.279684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:40.923432Z","time spent":"356.176716ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:24:43.051578Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132415399070849,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-30T16:24:43.553072Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132415399070849,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-30T16:24:44.053161Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132415399070849,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-30T16:24:44.553637Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132415399070849,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-30T16:24:44.722256Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.17153855s","expected-duration":"1s"} {"level":"info","ts":"2026-01-30T16:24:44.722514Z","caller":"traceutil/trace.go:171","msg":"trace[533726275] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:920; }","duration":"2.171717826s","start":"2026-01-30T16:24:42.550783Z","end":"2026-01-30T16:24:44.722501Z","steps":["trace[533726275] 'read index received' (duration: 2.171555612s)","trace[533726275] 'applied index is now lower than readState.Index' (duration: 161.454µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:24:44.722697Z","caller":"traceutil/trace.go:171","msg":"trace[929976308] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"2.172053513s","start":"2026-01-30T16:24:42.550635Z","end":"2026-01-30T16:24:44.722688Z","steps":["trace[929976308] 'process raft request' (duration: 2.171737415s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:44.722755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:42.55062Z","time spent":"2.172090485s","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:24:44.722881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.172096846s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-30T16:24:44.722897Z","caller":"traceutil/trace.go:171","msg":"trace[2111421381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:886; }","duration":"2.172124187s","start":"2026-01-30T16:24:42.550767Z","end":"2026-01-30T16:24:44.722891Z","steps":["trace[2111421381] 'agreement among raft nodes before linearized reading' (duration: 2.172067605s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:44.72291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:42.550761Z","time spent":"2.172146077s","remote":"127.0.0.1:49252","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-01-30T16:24:44.722991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.165392282s","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-01-30T16:24:44.723002Z","caller":"traceutil/trace.go:171","msg":"trace[292198526] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:886; }","duration":"2.165426713s","start":"2026-01-30T16:24:42.557572Z","end":"2026-01-30T16:24:44.722999Z","steps":["trace[292198526] 'agreement among raft nodes before linearized reading' (duration: 2.165392432s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:44.723014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:42.557558Z","time spent":"2.165452784s","remote":"127.0.0.1:49252","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-01-30T16:24:44.723114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.638294ms","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-01-30T16:24:44.723127Z","caller":"traceutil/trace.go:171","msg":"trace[1145967462] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:886; }","duration":"654.672214ms","start":"2026-01-30T16:24:44.068452Z","end":"2026-01-30T16:24:44.723124Z","steps":["trace[1145967462] 'agreement among raft nodes before linearized reading' (duration: 654.633823ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:44.723139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:44.06844Z","time spent":"654.696555ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-30T16:24:44.723193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.936634136s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-30T16:24:44.723204Z","caller":"traceutil/trace.go:171","msg":"trace[2129694277] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:886; }","duration":"1.936670037s","start":"2026-01-30T16:24:42.786531Z","end":"2026-01-30T16:24:44.723201Z","steps":["trace[2129694277] 'agreement among raft nodes before linearized reading' (duration: 1.936649577s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:44.723215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:42.786518Z","time spent":"1.936694168s","remote":"127.0.0.1:49378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":29,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-01-30T16:24:44.723281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.993195026s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:24:44.723291Z","caller":"traceutil/trace.go:171","msg":"trace[1569739070] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:886; }","duration":"1.993248668s","start":"2026-01-30T16:24:42.730039Z","end":"2026-01-30T16:24:44.723288Z","steps":["trace[1569739070] 'agreement among raft nodes before linearized reading' (duration: 1.993229447s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:44.723302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:42.730022Z","time spent":"1.993277579s","remote":"127.0.0.1:48980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-30T16:24:44.723381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.020972254s","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-01-30T16:24:44.723393Z","caller":"traceutil/trace.go:171","msg":"trace[1392908092] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:886; }","duration":"2.021000584s","start":"2026-01-30T16:24:42.702389Z","end":"2026-01-30T16:24:44.723389Z","steps":["trace[1392908092] 'agreement among raft nodes before linearized reading' (duration: 2.020964523s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:44.723405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:42.702376Z","time spent":"2.021025735s","remote":"127.0.0.1:49252","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-01-30T16:24:45.345411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.635797ms","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-01-30T16:24:45.345478Z","caller":"traceutil/trace.go:171","msg":"trace[123887560] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:887; }","duration":"232.711879ms","start":"2026-01-30T16:24:45.112756Z","end":"2026-01-30T16:24:45.345468Z","steps":["trace[123887560] 'range keys from in-memory index tree' (duration: 232.625597ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:45.345831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.934205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:24:45.345905Z","caller":"traceutil/trace.go:171","msg":"trace[2140889286] linearizableReadLoop","detail":"{readStateIndex:925; appliedIndex:922; }","duration":"533.995989ms","start":"2026-01-30T16:24:44.811904Z","end":"2026-01-30T16:24:45.3459Z","steps":["trace[2140889286] 'read index received' (duration: 403.91877ms)","trace[2140889286] 'applied index is now lower than readState.Index' (duration: 130.076839ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:24:45.346008Z","caller":"traceutil/trace.go:171","msg":"trace[1636916520] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"615.530993ms","start":"2026-01-30T16:24:44.730472Z","end":"2026-01-30T16:24:45.346003Z","steps":["trace[1636916520] 'process raft request' (duration: 485.387923ms)","trace[1636916520] 'compare' (duration: 129.776371ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:24:45.346038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:44.730468Z","time spent":"615.553425ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:24:45.346131Z","caller":"traceutil/trace.go:171","msg":"trace[983271743] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"615.618367ms","start":"2026-01-30T16:24:44.730508Z","end":"2026-01-30T16:24:45.346127Z","steps":["trace[983271743] 'process raft request' (duration: 615.3522ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:45.346153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:44.730505Z","time spent":"615.635967ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:24:45.346208Z","caller":"traceutil/trace.go:171","msg":"trace[380342502] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"609.757064ms","start":"2026-01-30T16:24:44.736447Z","end":"2026-01-30T16:24:45.346204Z","steps":["trace[380342502] 'process raft request' (duration: 609.435926ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:45.346231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:44.736436Z","time spent":"609.782856ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:24:45.3463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.396319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:24:45.346314Z","caller":"traceutil/trace.go:171","msg":"trace[456608310] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:890; }","duration":"534.4338ms","start":"2026-01-30T16:24:44.811874Z","end":"2026-01-30T16:24:45.346308Z","steps":["trace[456608310] 'agreement among raft nodes before linearized reading' (duration: 534.41148ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:45.346326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:44.811861Z","time spent":"534.462611ms","remote":"127.0.0.1:48980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-30T16:24:45.346395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.143116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:24:45.346406Z","caller":"traceutil/trace.go:171","msg":"trace[1142610704] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:890; }","duration":"497.174118ms","start":"2026-01-30T16:24:44.849229Z","end":"2026-01-30T16:24:45.346403Z","steps":["trace[1142610704] 'agreement among raft nodes before linearized reading' (duration: 497.153887ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:45.346418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:44.849214Z","time spent":"497.200438ms","remote":"127.0.0.1:49458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-01-30T16:24:47.012837Z","caller":"traceutil/trace.go:171","msg":"trace[213199333] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"150.040524ms","start":"2026-01-30T16:24:46.862784Z","end":"2026-01-30T16:24:47.012824Z","steps":["trace[213199333] 'process raft request' (duration: 149.684825ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:24:47.326705Z","caller":"traceutil/trace.go:171","msg":"trace[1857894394] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"272.438464ms","start":"2026-01-30T16:24:47.05425Z","end":"2026-01-30T16:24:47.326689Z","steps":["trace[1857894394] 'process raft request' (duration: 206.913193ms)","trace[1857894394] 'compare' (duration: 65.443609ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:24:47.497323Z","caller":"traceutil/trace.go:171","msg":"trace[1226360010] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"145.425215ms","start":"2026-01-30T16:24:47.351881Z","end":"2026-01-30T16:24:47.497306Z","steps":["trace[1226360010] 'process raft request' (duration: 130.007927ms)","trace[1226360010] 'compare' (duration: 15.228283ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:24:47.502703Z","caller":"traceutil/trace.go:171","msg":"trace[1408979678] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"149.514571ms","start":"2026-01-30T16:24:47.35318Z","end":"2026-01-30T16:24:47.502695Z","steps":["trace[1408979678] 'process raft request' (duration: 149.45327ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:24:47.50281Z","caller":"traceutil/trace.go:171","msg":"trace[874941086] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"149.006127ms","start":"2026-01-30T16:24:47.353799Z","end":"2026-01-30T16:24:47.502805Z","steps":["trace[874941086] 'process raft request' (duration: 148.876054ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:52.137446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.871248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:24:52.137604Z","caller":"traceutil/trace.go:171","msg":"trace[493596729] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"511.388834ms","start":"2026-01-30T16:24:51.626205Z","end":"2026-01-30T16:24:52.137594Z","steps":["trace[493596729] 'process raft request' (duration: 511.368624ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:52.137638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:51.626185Z","time spent":"511.436885ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:24:52.137785Z","caller":"traceutil/trace.go:171","msg":"trace[589244187] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"571.447025ms","start":"2026-01-30T16:24:51.566332Z","end":"2026-01-30T16:24:52.137779Z","steps":["trace[589244187] 'process raft request' (duration: 265.189818ms)","trace[589244187] 'compare' (duration: 305.776565ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:24:52.137808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:51.566321Z","time spent":"571.475435ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:24:52.13786Z","caller":"traceutil/trace.go:171","msg":"trace[1224231616] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"571.427025ms","start":"2026-01-30T16:24:51.566429Z","end":"2026-01-30T16:24:52.137856Z","steps":["trace[1224231616] 'process raft request' (duration: 571.075196ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:52.137879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:51.566423Z","time spent":"571.445915ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:24:52.636186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.76057ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-01-30T16:24:53.051325Z","caller":"traceutil/trace.go:171","msg":"trace[1916083129] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"268.433591ms","start":"2026-01-30T16:24:52.782879Z","end":"2026-01-30T16:24:53.051312Z","steps":["trace[1916083129] 'process raft request' (duration: 268.112522ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:53.326762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.252328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:24:53.327177Z","caller":"traceutil/trace.go:171","msg":"trace[1536872504] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"272.754112ms","start":"2026-01-30T16:24:53.054404Z","end":"2026-01-30T16:24:53.327158Z","steps":["trace[1536872504] 'process raft request' (duration: 112.015412ms)","trace[1536872504] 'compare' (duration: 160.151505ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:24:53.735266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.868461ms","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-01-30T16:24:53.735321Z","caller":"traceutil/trace.go:171","msg":"trace[1819343974] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:910; }","duration":"168.962614ms","start":"2026-01-30T16:24:53.566345Z","end":"2026-01-30T16:24:53.735308Z","steps":["trace[1819343974] 'range keys from in-memory index tree' (duration: 168.734358ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:54.441604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.45901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:24:54.441665Z","caller":"traceutil/trace.go:171","msg":"trace[110074516] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"215.115505ms","start":"2026-01-30T16:24:54.226539Z","end":"2026-01-30T16:24:54.441654Z","steps":["trace[110074516] 'process raft request' (duration: 58.560802ms)","trace[110074516] 'compare' (duration: 156.288276ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:24:58.806193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.507632828s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:24:58.806294Z","caller":"traceutil/trace.go:171","msg":"trace[334845189] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"945.70861ms","start":"2026-01-30T16:24:57.860575Z","end":"2026-01-30T16:24:58.806284Z","steps":["trace[334845189] 'process raft request' (duration: 945.675539ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:58.806325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:57.860556Z","time spent":"945.75432ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:24:58.806428Z","caller":"traceutil/trace.go:171","msg":"trace[1794204058] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"1.784867006s","start":"2026-01-30T16:24:57.021553Z","end":"2026-01-30T16:24:58.80642Z","steps":["trace[1794204058] 'process raft request' (duration: 276.959371ms)","trace[1794204058] 'compare' (duration: 1.507388132s)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:24:58.806454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:57.021542Z","time spent":"1.784898267s","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:24:58.806518Z","caller":"traceutil/trace.go:171","msg":"trace[983331458] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:956; }","duration":"1.745359807s","start":"2026-01-30T16:24:57.061154Z","end":"2026-01-30T16:24:58.806513Z","steps":["trace[983331458] 'read index received' (duration: 237.324428ms)","trace[983331458] 'applied index is now lower than readState.Index' (duration: 1.508034769s)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:24:58.80668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.745530501s","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-01-30T16:24:58.806694Z","caller":"traceutil/trace.go:171","msg":"trace[566715606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:920; }","duration":"1.745566572s","start":"2026-01-30T16:24:57.061122Z","end":"2026-01-30T16:24:58.806689Z","steps":["trace[566715606] 'agreement among raft nodes before linearized reading' (duration: 1.745511311s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:58.806706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:57.061109Z","time spent":"1.745594282s","remote":"127.0.0.1:49252","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-01-30T16:24:58.806804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.471214028s","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-01-30T16:24:58.806816Z","caller":"traceutil/trace.go:171","msg":"trace[1936806770] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:920; }","duration":"1.471248728s","start":"2026-01-30T16:24:57.335564Z","end":"2026-01-30T16:24:58.806812Z","steps":["trace[1936806770] 'agreement among raft nodes before linearized reading' (duration: 1.471207147s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:58.806827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:57.335552Z","time spent":"1.4712731s","remote":"127.0.0.1:49252","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-01-30T16:24:58.807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.33909ms","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-01-30T16:24:58.807013Z","caller":"traceutil/trace.go:171","msg":"trace[1089800972] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:920; }","duration":"574.37631ms","start":"2026-01-30T16:24:58.232633Z","end":"2026-01-30T16:24:58.807009Z","steps":["trace[1089800972] 'agreement among raft nodes before linearized reading' (duration: 574.344009ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:58.807026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:58.232619Z","time spent":"574.403362ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-30T16:24:58.807094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.704814ms","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-01-30T16:24:58.807107Z","caller":"traceutil/trace.go:171","msg":"trace[1381681561] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:920; }","duration":"994.740265ms","start":"2026-01-30T16:24:57.812363Z","end":"2026-01-30T16:24:58.807103Z","steps":["trace[1381681561] 'agreement among raft nodes before linearized reading' (duration: 994.707574ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:58.807117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:57.81235Z","time spent":"994.765096ms","remote":"127.0.0.1:49252","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-01-30T16:24:58.807171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.078350894s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:24:58.807184Z","caller":"traceutil/trace.go:171","msg":"trace[668316918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:920; }","duration":"1.078394465s","start":"2026-01-30T16:24:57.728786Z","end":"2026-01-30T16:24:58.807181Z","steps":["trace[668316918] 'agreement among raft nodes before linearized reading' (duration: 1.078375594s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:58.807194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:57.728771Z","time spent":"1.078421356s","remote":"127.0.0.1:48980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-30T16:24:58.807255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.235801409s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:24:58.807269Z","caller":"traceutil/trace.go:171","msg":"trace[1914059100] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:920; }","duration":"1.235838721s","start":"2026-01-30T16:24:57.571427Z","end":"2026-01-30T16:24:58.807266Z","steps":["trace[1914059100] 'agreement among raft nodes before linearized reading' (duration: 1.23581804s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:58.807281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:57.571415Z","time spent":"1.235862451s","remote":"127.0.0.1:49494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-01-30T16:24:59.250801Z","caller":"traceutil/trace.go:171","msg":"trace[780696897] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:959; }","duration":"412.931403ms","start":"2026-01-30T16:24:58.837855Z","end":"2026-01-30T16:24:59.250786Z","steps":["trace[780696897] 'read index received' (duration: 412.781158ms)","trace[780696897] 'applied index is now lower than readState.Index' (duration: 149.625µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:24:59.250817Z","caller":"traceutil/trace.go:171","msg":"trace[773450577] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"423.569718ms","start":"2026-01-30T16:24:58.827222Z","end":"2026-01-30T16:24:59.250792Z","steps":["trace[773450577] 'process raft request' (duration: 423.503696ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:24:59.250885Z","caller":"traceutil/trace.go:171","msg":"trace[1897219012] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"423.631779ms","start":"2026-01-30T16:24:58.827247Z","end":"2026-01-30T16:24:59.250879Z","steps":["trace[1897219012] 'process raft request' (duration: 423.500326ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:59.250938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:58.827217Z","time spent":"423.65303ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:24:59.250957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:58.827244Z","time spent":"423.65814ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:24:59.251116Z","caller":"traceutil/trace.go:171","msg":"trace[1235645248] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"423.955298ms","start":"2026-01-30T16:24:58.827156Z","end":"2026-01-30T16:24:59.251111Z","steps":["trace[1235645248] 'process raft request' (duration: 423.479735ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:59.25115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:58.827147Z","time spent":"423.989909ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:24:59.251178Z","caller":"traceutil/trace.go:171","msg":"trace[1494895881] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"423.657291ms","start":"2026-01-30T16:24:58.827515Z","end":"2026-01-30T16:24:59.251172Z","steps":["trace[1494895881] 'process raft request' (duration: 423.24775ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:59.251204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:58.827497Z","time spent":"423.693111ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:24:59.251254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.882022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:24:59.251269Z","caller":"traceutil/trace.go:171","msg":"trace[632298854] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:925; }","duration":"419.905843ms","start":"2026-01-30T16:24:58.831358Z","end":"2026-01-30T16:24:59.251264Z","steps":["trace[632298854] 'agreement among raft nodes before linearized reading' (duration: 419.880282ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:59.251281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:58.831353Z","time spent":"419.926103ms","remote":"127.0.0.1:48980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-30T16:24:59.251403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.765903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:24:59.25142Z","caller":"traceutil/trace.go:171","msg":"trace[1260702941] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:925; }","duration":"351.782854ms","start":"2026-01-30T16:24:58.899633Z","end":"2026-01-30T16:24:59.251416Z","steps":["trace[1260702941] 'agreement among raft nodes before linearized reading' (duration: 351.709572ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:24:59.251431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:24:58.899597Z","time spent":"351.830875ms","remote":"127.0.0.1:49144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-01-30T16:25:03.00504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.526099ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-01-30T16:25:03.005523Z","caller":"traceutil/trace.go:171","msg":"trace[618564474] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:968; }","duration":"154.939491ms","start":"2026-01-30T16:25:02.850563Z","end":"2026-01-30T16:25:03.005502Z","steps":["trace[618564474] 'read index received' (duration: 107.481456ms)","trace[618564474] 'applied index is now lower than readState.Index' (duration: 47.457095ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:25:03.005583Z","caller":"traceutil/trace.go:171","msg":"trace[1740444227] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"780.616017ms","start":"2026-01-30T16:25:02.224961Z","end":"2026-01-30T16:25:03.005577Z","steps":["trace[1740444227] 'process raft request' (duration: 780.149625ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:25:03.00565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:25:02.22495Z","time spent":"780.654338ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:25:03.381884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.295698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-30T16:25:03.381952Z","caller":"traceutil/trace.go:171","msg":"trace[772411147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:931; }","duration":"531.407531ms","start":"2026-01-30T16:25:02.850526Z","end":"2026-01-30T16:25:03.381933Z","steps":["trace[772411147] 'agreement among raft nodes before linearized reading' (duration: 155.039204ms)","trace[772411147] 'range keys from in-memory index tree' (duration: 376.203903ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:25:03.381989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:25:02.85051Z","time spent":"531.471883ms","remote":"127.0.0.1:49252","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-01-30T16:25:03.382184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.106772ms","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-01-30T16:25:03.382216Z","caller":"traceutil/trace.go:171","msg":"trace[673070615] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:931; }","duration":"115.169723ms","start":"2026-01-30T16:25:03.267033Z","end":"2026-01-30T16:25:03.382203Z","steps":["trace[673070615] 'range keys from in-memory index tree' (duration: 114.995429ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:25:03.382231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.376207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-30T16:25:03.382277Z","caller":"traceutil/trace.go:171","msg":"trace[444472580] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:931; }","duration":"247.47391ms","start":"2026-01-30T16:25:03.13479Z","end":"2026-01-30T16:25:03.382264Z","steps":["trace[444472580] 'count revisions from in-memory index tree' (duration: 247.322916ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:25:03.382601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.077122ms","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-01-30T16:25:03.382615Z","caller":"traceutil/trace.go:171","msg":"trace[215312797] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:931; }","duration":"110.111063ms","start":"2026-01-30T16:25:03.2725Z","end":"2026-01-30T16:25:03.382611Z","steps":["trace[215312797] 'range keys from in-memory index tree' (duration: 110.00041ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:25:03.382704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.213667ms","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-01-30T16:25:03.382716Z","caller":"traceutil/trace.go:171","msg":"trace[125596190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:931; }","duration":"112.268328ms","start":"2026-01-30T16:25:03.270444Z","end":"2026-01-30T16:25:03.382712Z","steps":["trace[125596190] 'range keys from in-memory index tree' (duration: 112.137645ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:25:03.388001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.53827ms","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-01-30T16:25:03.389093Z","caller":"traceutil/trace.go:171","msg":"trace[310789987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:931; }","duration":"118.624242ms","start":"2026-01-30T16:25:03.269433Z","end":"2026-01-30T16:25:03.388057Z","steps":["trace[310789987] 'range keys from in-memory index tree' (duration: 118.470079ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:25:03.569753Z","caller":"traceutil/trace.go:171","msg":"trace[875935030] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:971; }","duration":"102.192379ms","start":"2026-01-30T16:25:03.467543Z","end":"2026-01-30T16:25:03.569736Z","steps":["trace[875935030] 'read index received' (duration: 83.416064ms)","trace[875935030] 'applied index is now lower than readState.Index' (duration: 18.775355ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:25:03.569811Z","caller":"traceutil/trace.go:171","msg":"trace[1287384642] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"180.48757ms","start":"2026-01-30T16:25:03.389279Z","end":"2026-01-30T16:25:03.569767Z","steps":["trace[1287384642] 'process raft request' (duration: 161.731816ms)","trace[1287384642] 'compare' (duration: 18.421116ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:25:03.569861Z","caller":"traceutil/trace.go:171","msg":"trace[585585571] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"178.8884ms","start":"2026-01-30T16:25:03.390968Z","end":"2026-01-30T16:25:03.569856Z","steps":["trace[585585571] 'process raft request' (duration: 178.718505ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:25:03.570006Z","caller":"traceutil/trace.go:171","msg":"trace[2054755566] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"177.572494ms","start":"2026-01-30T16:25:03.392428Z","end":"2026-01-30T16:25:03.57Z","steps":["trace[2054755566] 'process raft request' (duration: 177.281957ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:25:03.569843Z","caller":"traceutil/trace.go:171","msg":"trace[77547639] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"180.523561ms","start":"2026-01-30T16:25:03.389312Z","end":"2026-01-30T16:25:03.569836Z","steps":["trace[77547639] 'process raft request' (duration: 180.347177ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:25:03.576872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.317392ms","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-01-30T16:25:03.576956Z","caller":"traceutil/trace.go:171","msg":"trace[1438891153] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:936; }","duration":"109.476047ms","start":"2026-01-30T16:25:03.467469Z","end":"2026-01-30T16:25:03.576945Z","steps":["trace[1438891153] 'agreement among raft nodes before linearized reading' (duration: 102.577328ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:25:04.237067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.600079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:25:04.237135Z","caller":"traceutil/trace.go:171","msg":"trace[267636829] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:936; }","duration":"504.674671ms","start":"2026-01-30T16:25:03.732444Z","end":"2026-01-30T16:25:04.237118Z","steps":["trace[267636829] 'range keys from in-memory index tree' (duration: 504.532217ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:25:04.237173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:25:03.732394Z","time spent":"504.771425ms","remote":"127.0.0.1:48980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-30T16:25:17.22545Z","caller":"traceutil/trace.go:171","msg":"trace[1715157540] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"234.904397ms","start":"2026-01-30T16:25:16.990525Z","end":"2026-01-30T16:25:17.225429Z","steps":["trace[1715157540] 'process raft request' (duration: 227.187909ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:25:20.62974Z","caller":"traceutil/trace.go:171","msg":"trace[658918111] linearizableReadLoop","detail":"{readStateIndex:1088; appliedIndex:1087; }","duration":"100.907859ms","start":"2026-01-30T16:25:20.52879Z","end":"2026-01-30T16:25:20.629698Z","steps":["trace[658918111] 'read index received' (duration: 100.617141ms)","trace[658918111] 'applied index is now lower than readState.Index' (duration: 289.728µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:25:20.630023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.249067ms","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-01-30T16:25:20.630094Z","caller":"traceutil/trace.go:171","msg":"trace[329507711] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1045; }","duration":"101.36432ms","start":"2026-01-30T16:25:20.52872Z","end":"2026-01-30T16:25:20.630084Z","steps":["trace[329507711] 'agreement among raft nodes before linearized reading' (duration: 101.179286ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:25:20.630253Z","caller":"traceutil/trace.go:171","msg":"trace[1357649723] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"240.032806ms","start":"2026-01-30T16:25:20.390212Z","end":"2026-01-30T16:25:20.630245Z","steps":["trace[1357649723] 'process raft request' (duration: 239.271077ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:25:21.315474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.658761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:25:21.31557Z","caller":"traceutil/trace.go:171","msg":"trace[1652888334] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"678.162947ms","start":"2026-01-30T16:25:20.637389Z","end":"2026-01-30T16:25:21.315552Z","steps":["trace[1652888334] 'process raft request' (duration: 82.349902ms)","trace[1652888334] 'compare' (duration: 595.342313ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:25:21.315628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:25:20.63736Z","time spent":"678.240118ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:25:21.778312Z","caller":"traceutil/trace.go:171","msg":"trace[554249578] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"145.431931ms","start":"2026-01-30T16:25:21.632857Z","end":"2026-01-30T16:25:21.778289Z","steps":["trace[554249578] 'process raft request' (duration: 144.581449ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:27:56.675593Z","caller":"traceutil/trace.go:171","msg":"trace[870799845] transaction","detail":"{read_only:false; response_revision:1684; number_of_response:1; }","duration":"102.415514ms","start":"2026-01-30T16:27:56.573144Z","end":"2026-01-30T16:27:56.67556Z","steps":["trace[870799845] 'process raft request' (duration: 70.438451ms)","trace[870799845] 'compare' (duration: 31.47388ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:28:32.169408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.283943ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-01-30T16:28:32.169663Z","caller":"traceutil/trace.go:171","msg":"trace[1387041159] linearizableReadLoop","detail":"{readStateIndex:1940; appliedIndex:1939; }","duration":"200.091616ms","start":"2026-01-30T16:28:31.969537Z","end":"2026-01-30T16:28:32.169629Z","steps":["trace[1387041159] 'read index received' (duration: 39.893568ms)","trace[1387041159] 'applied index is now lower than readState.Index' (duration: 160.196488ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:28:32.169853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.318572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1243"} {"level":"info","ts":"2026-01-30T16:28:32.169902Z","caller":"traceutil/trace.go:171","msg":"trace[1765216277] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1857; }","duration":"200.500876ms","start":"2026-01-30T16:28:31.969392Z","end":"2026-01-30T16:28:32.169893Z","steps":["trace[1765216277] 'agreement among raft nodes before linearized reading' (duration: 200.315522ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:28:35.185735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.641234ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:28:35.185797Z","caller":"traceutil/trace.go:171","msg":"trace[308273252] linearizableReadLoop","detail":"{readStateIndex:1953; appliedIndex:1951; }","duration":"386.776341ms","start":"2026-01-30T16:28:34.79901Z","end":"2026-01-30T16:28:35.185786Z","steps":["trace[308273252] 'read index received' (duration: 46.897885ms)","trace[308273252] 'applied index is now lower than readState.Index' (duration: 339.877866ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:28:35.185931Z","caller":"traceutil/trace.go:171","msg":"trace[596115636] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"396.352848ms","start":"2026-01-30T16:28:34.78957Z","end":"2026-01-30T16:28:35.185923Z","steps":["trace[596115636] 'process raft request' (duration: 254.468218ms)","trace[596115636] 'compare' (duration: 141.385909ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:28:35.185969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:28:34.789542Z","time spent":"396.406819ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:28:35.186146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.133899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-30T16:28:35.186166Z","caller":"traceutil/trace.go:171","msg":"trace[1566541692] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1870; }","duration":"387.17666ms","start":"2026-01-30T16:28:34.798983Z","end":"2026-01-30T16:28:35.186159Z","steps":["trace[1566541692] 'agreement among raft nodes before linearized reading' (duration: 387.103238ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:28:35.186184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:28:34.79897Z","time spent":"387.209521ms","remote":"127.0.0.1:49252","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-01-30T16:28:35.186293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.652446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-01-30T16:28:35.186309Z","caller":"traceutil/trace.go:171","msg":"trace[2041214929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1870; }","duration":"335.711016ms","start":"2026-01-30T16:28:34.850594Z","end":"2026-01-30T16:28:35.186305Z","steps":["trace[2041214929] 'agreement among raft nodes before linearized reading' (duration: 335.663395ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:28:35.186328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:28:34.850571Z","time spent":"335.753238ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-30T16:28:35.186476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.586886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-01-30T16:28:35.186493Z","caller":"traceutil/trace.go:171","msg":"trace[1408040644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1870; }","duration":"293.639006ms","start":"2026-01-30T16:28:34.892848Z","end":"2026-01-30T16:28:35.186487Z","steps":["trace[1408040644] 'agreement among raft nodes before linearized reading' (duration: 293.589555ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:28:43.996941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.734444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:28:43.997033Z","caller":"traceutil/trace.go:171","msg":"trace[340346838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1916; }","duration":"265.866599ms","start":"2026-01-30T16:28:43.731143Z","end":"2026-01-30T16:28:43.997009Z","steps":["trace[340346838] 'range keys from in-memory index tree' (duration: 265.660923ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:28:43.998849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.96201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-01-30T16:28:43.998908Z","caller":"traceutil/trace.go:171","msg":"trace[1435667861] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver; range_end:; response_count:1; response_revision:1916; }","duration":"242.243807ms","start":"2026-01-30T16:28:43.756648Z","end":"2026-01-30T16:28:43.998891Z","steps":["trace[1435667861] 'range keys from in-memory index tree' (duration: 240.501066ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:28:43.999441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.812547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:attachdetach-controller\" ","response":"range_response_count:1 size:952"} {"level":"info","ts":"2026-01-30T16:28:43.999484Z","caller":"traceutil/trace.go:171","msg":"trace[1432194803] range","detail":"{range_begin:/registry/clusterroles/system:controller:attachdetach-controller; range_end:; response_count:1; response_revision:1916; }","duration":"175.890709ms","start":"2026-01-30T16:28:43.823583Z","end":"2026-01-30T16:28:43.999473Z","steps":["trace[1432194803] 'range keys from in-memory index tree' (duration: 175.47672ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:28:51.667453Z","caller":"traceutil/trace.go:171","msg":"trace[100167660] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"196.742426ms","start":"2026-01-30T16:28:51.470685Z","end":"2026-01-30T16:28:51.667427Z","steps":["trace[100167660] 'process raft request' (duration: 196.584962ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:28:51.724899Z","caller":"traceutil/trace.go:171","msg":"trace[1638009145] linearizableReadLoop","detail":"{readStateIndex:2033; appliedIndex:2032; }","duration":"249.428977ms","start":"2026-01-30T16:28:51.475428Z","end":"2026-01-30T16:28:51.724857Z","steps":["trace[1638009145] 'read index received' (duration: 192.572286ms)","trace[1638009145] 'applied index is now lower than readState.Index' (duration: 56.855631ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:28:51.725477Z","caller":"traceutil/trace.go:171","msg":"trace[22833830] transaction","detail":"{read_only:false; response_revision:1946; number_of_response:1; }","duration":"253.082824ms","start":"2026-01-30T16:28:51.47238Z","end":"2026-01-30T16:28:51.725462Z","steps":["trace[22833830] 'process raft request' (duration: 252.314886ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:28:51.726704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.250922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-01-30T16:28:51.727291Z","caller":"traceutil/trace.go:171","msg":"trace[1556626502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1946; }","duration":"251.869706ms","start":"2026-01-30T16:28:51.475402Z","end":"2026-01-30T16:28:51.727272Z","steps":["trace[1556626502] 'agreement among raft nodes before linearized reading' (duration: 251.144979ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:28:51.727853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.546696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-controller-manager\" ","response":"range_response_count:1 size:1041"} {"level":"info","ts":"2026-01-30T16:28:51.728397Z","caller":"traceutil/trace.go:171","msg":"trace[692696357] range","detail":"{range_begin:/registry/clusterroles/system:kube-controller-manager; range_end:; response_count:1; response_revision:1946; }","duration":"172.17098ms","start":"2026-01-30T16:28:51.55621Z","end":"2026-01-30T16:28:51.728381Z","steps":["trace[692696357] 'agreement among raft nodes before linearized reading' (duration: 171.547696ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:28:51.729623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.845691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:replicaset-controller\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-01-30T16:28:51.72976Z","caller":"traceutil/trace.go:171","msg":"trace[1821575962] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:replicaset-controller; range_end:; response_count:1; response_revision:1946; }","duration":"157.989265ms","start":"2026-01-30T16:28:51.571706Z","end":"2026-01-30T16:28:51.729695Z","steps":["trace[1821575962] 'agreement among raft nodes before linearized reading' (duration: 157.75817ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:28:52.107637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.986733ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-01-30T16:28:53.931707Z","caller":"traceutil/trace.go:171","msg":"trace[1281128722] linearizableReadLoop","detail":"{readStateIndex:2044; appliedIndex:2043; }","duration":"101.478562ms","start":"2026-01-30T16:28:53.830198Z","end":"2026-01-30T16:28:53.931676Z","steps":["trace[1281128722] 'read index received' (duration: 25.388073ms)","trace[1281128722] 'applied index is now lower than readState.Index' (duration: 76.089019ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:28:53.931955Z","caller":"traceutil/trace.go:171","msg":"trace[2090449029] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"178.226836ms","start":"2026-01-30T16:28:53.753677Z","end":"2026-01-30T16:28:53.931904Z","steps":["trace[2090449029] 'process raft request' (duration: 101.957834ms)","trace[2090449029] 'compare' (duration: 75.857952ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:28:53.932254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.039335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-01-30T16:28:53.932336Z","caller":"traceutil/trace.go:171","msg":"trace[2075838190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1956; }","duration":"102.185068ms","start":"2026-01-30T16:28:53.830132Z","end":"2026-01-30T16:28:53.932317Z","steps":["trace[2075838190] 'agreement among raft nodes before linearized reading' (duration: 102.040974ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:28:57.337603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.430033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/default/\" range_end:\"/registry/configmaps/default0\" limit:500 ","response":"range_response_count:1 size:1738"} {"level":"info","ts":"2026-01-30T16:28:57.337717Z","caller":"traceutil/trace.go:171","msg":"trace[379204343] range","detail":"{range_begin:/registry/configmaps/default/; range_end:/registry/configmaps/default0; response_count:1; response_revision:1968; }","duration":"123.646019ms","start":"2026-01-30T16:28:57.214049Z","end":"2026-01-30T16:28:57.337695Z","steps":["trace[379204343] 'range keys from in-memory index tree' (duration: 123.328102ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:28:57.337627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.620243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-node-lease/\" range_end:\"/registry/configmaps/kube-node-lease0\" limit:500 ","response":"range_response_count:1 size:1754"} {"level":"info","ts":"2026-01-30T16:28:57.337814Z","caller":"traceutil/trace.go:171","msg":"trace[63757009] range","detail":"{range_begin:/registry/configmaps/kube-node-lease/; range_end:/registry/configmaps/kube-node-lease0; response_count:1; response_revision:1968; }","duration":"132.869658ms","start":"2026-01-30T16:28:57.204919Z","end":"2026-01-30T16:28:57.337789Z","steps":["trace[63757009] 'range keys from in-memory index tree' (duration: 132.499889ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:28:57.535322Z","caller":"traceutil/trace.go:171","msg":"trace[143065717] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"117.606675ms","start":"2026-01-30T16:28:57.417696Z","end":"2026-01-30T16:28:57.535303Z","steps":["trace[143065717] 'process raft request' (duration: 56.068263ms)","trace[143065717] 'compare' (duration: 61.214305ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:29:04.149501Z","caller":"traceutil/trace.go:171","msg":"trace[1523703916] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"183.715515ms","start":"2026-01-30T16:29:03.965751Z","end":"2026-01-30T16:29:04.149466Z","steps":["trace[1523703916] 'process raft request' (duration: 84.413616ms)","trace[1523703916] 'compare' (duration: 99.131595ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:29:04.159744Z","caller":"traceutil/trace.go:171","msg":"trace[2099318572] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"190.459777ms","start":"2026-01-30T16:29:03.969262Z","end":"2026-01-30T16:29:04.159722Z","steps":["trace[2099318572] 'process raft request' (duration: 190.248192ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:29:04.159736Z","caller":"traceutil/trace.go:171","msg":"trace[1106272200] linearizableReadLoop","detail":"{readStateIndex:2086; appliedIndex:2084; }","duration":"190.380755ms","start":"2026-01-30T16:29:03.969329Z","end":"2026-01-30T16:29:04.15971Z","steps":["trace[1106272200] 'read index received' (duration: 80.845672ms)","trace[1106272200] 'applied index is now lower than readState.Index' (duration: 109.534133ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:29:04.160149Z","caller":"traceutil/trace.go:171","msg":"trace[343335523] transaction","detail":"{read_only:false; response_revision:1997; number_of_response:1; }","duration":"189.151765ms","start":"2026-01-30T16:29:03.970966Z","end":"2026-01-30T16:29:04.160118Z","steps":["trace[343335523] 'process raft request' (duration: 188.682694ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:29:04.16059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.239135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6bh9w\" ","response":"range_response_count:1 size:18058"} {"level":"info","ts":"2026-01-30T16:29:04.160749Z","caller":"traceutil/trace.go:171","msg":"trace[2008341406] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6bh9w; range_end:; response_count:1; response_revision:1997; }","duration":"191.360988ms","start":"2026-01-30T16:29:03.969294Z","end":"2026-01-30T16:29:04.160655Z","steps":["trace[2008341406] 'agreement among raft nodes before linearized reading' (duration: 191.143873ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:29:04.161384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.315705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-gjs7t\" ","response":"range_response_count:1 size:4624"} {"level":"info","ts":"2026-01-30T16:29:04.161532Z","caller":"traceutil/trace.go:171","msg":"trace[194427987] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-gjs7t; range_end:; response_count:1; response_revision:1997; }","duration":"124.50046ms","start":"2026-01-30T16:29:04.03702Z","end":"2026-01-30T16:29:04.16152Z","steps":["trace[194427987] 'agreement among raft nodes before linearized reading' (duration: 123.950356ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:29:04.162996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.981485ms","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:532"} {"level":"info","ts":"2026-01-30T16:29:04.163115Z","caller":"traceutil/trace.go:171","msg":"trace[57613800] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1997; }","duration":"113.130759ms","start":"2026-01-30T16:29:04.049973Z","end":"2026-01-30T16:29:04.163104Z","steps":["trace[57613800] 'agreement among raft nodes before linearized reading' (duration: 111.966751ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:29:05.364201Z","caller":"traceutil/trace.go:171","msg":"trace[544568310] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"133.026472ms","start":"2026-01-30T16:29:05.231148Z","end":"2026-01-30T16:29:05.364174Z","steps":["trace[544568310] 'process raft request' (duration: 132.835637ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:29:06.670523Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132415399074326,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-30T16:29:07.051064Z","caller":"traceutil/trace.go:171","msg":"trace[803626813] linearizableReadLoop","detail":"{readStateIndex:2092; appliedIndex:2091; }","duration":"881.054908ms","start":"2026-01-30T16:29:06.16998Z","end":"2026-01-30T16:29:07.051035Z","steps":["trace[803626813] 'read index received' (duration: 880.809482ms)","trace[803626813] 'applied index is now lower than readState.Index' (duration: 244.426µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:29:07.051351Z","caller":"traceutil/trace.go:171","msg":"trace[1070740701] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"892.90797ms","start":"2026-01-30T16:29:06.158401Z","end":"2026-01-30T16:29:07.051309Z","steps":["trace[1070740701] 'process raft request' (duration: 892.46186ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:29:07.051566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:29:06.158381Z","time spent":"893.063455ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:29:07.051941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.956839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-01-30T16:29:07.053264Z","caller":"traceutil/trace.go:171","msg":"trace[25463564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2002; }","duration":"883.305352ms","start":"2026-01-30T16:29:06.169939Z","end":"2026-01-30T16:29:07.053244Z","steps":["trace[25463564] 'agreement among raft nodes before linearized reading' (duration: 881.877197ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:29:07.056439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:29:06.169925Z","time spent":"886.502278ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-30T16:29:07.05259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.840548ms","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-01-30T16:29:07.056741Z","caller":"traceutil/trace.go:171","msg":"trace[1627568420] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2002; }","duration":"833.038537ms","start":"2026-01-30T16:29:06.223675Z","end":"2026-01-30T16:29:07.056713Z","steps":["trace[1627568420] 'agreement among raft nodes before linearized reading' (duration: 828.735475ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:29:07.056826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:29:06.223658Z","time spent":"833.13641ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-01-30T16:29:07.053547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.543423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system:controller:bootstrap-signer\" ","response":"range_response_count:1 size:741"} {"level":"info","ts":"2026-01-30T16:29:07.057191Z","caller":"traceutil/trace.go:171","msg":"trace[2060949978] range","detail":"{range_begin:/registry/rolebindings/kube-system/system:controller:bootstrap-signer; range_end:; response_count:1; response_revision:2002; }","duration":"774.219259ms","start":"2026-01-30T16:29:06.282963Z","end":"2026-01-30T16:29:07.057182Z","steps":["trace[2060949978] 'agreement among raft nodes before linearized reading' (duration: 770.482751ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:29:07.057263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:29:06.282952Z","time spent":"774.302462ms","remote":"127.0.0.1:49344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":763,"request content":"key:\"/registry/rolebindings/kube-system/system:controller:bootstrap-signer\" "} {"level":"warn","ts":"2026-01-30T16:29:07.053819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.496835ms","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:536"} {"level":"info","ts":"2026-01-30T16:29:07.057688Z","caller":"traceutil/trace.go:171","msg":"trace[430871152] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2002; }","duration":"127.305495ms","start":"2026-01-30T16:29:06.930261Z","end":"2026-01-30T16:29:07.057566Z","steps":["trace[430871152] 'agreement among raft nodes before linearized reading' (duration: 123.502035ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:29:07.053855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.367551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:29:07.058141Z","caller":"traceutil/trace.go:171","msg":"trace[1887708413] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2002; }","duration":"326.707295ms","start":"2026-01-30T16:29:06.731422Z","end":"2026-01-30T16:29:07.058129Z","steps":["trace[1887708413] 'agreement among raft nodes before linearized reading' (duration: 322.415753ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:29:07.058201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:29:06.731398Z","time spent":"326.790106ms","remote":"127.0.0.1:48980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-30T16:29:07.055658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.793458ms","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:532"} {"level":"info","ts":"2026-01-30T16:29:07.058473Z","caller":"traceutil/trace.go:171","msg":"trace[1553001949] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2002; }","duration":"810.630626ms","start":"2026-01-30T16:29:06.247833Z","end":"2026-01-30T16:29:07.058464Z","steps":["trace[1553001949] 'agreement among raft nodes before linearized reading' (duration: 807.725007ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:29:07.058532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:29:06.247826Z","time spent":"810.696557ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-01-30T16:29:07.055707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.812981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system::leader-locking-kube-scheduler\" ","response":"range_response_count:1 size:808"} {"level":"info","ts":"2026-01-30T16:29:07.058742Z","caller":"traceutil/trace.go:171","msg":"trace[241788532] range","detail":"{range_begin:/registry/rolebindings/kube-system/system::leader-locking-kube-scheduler; range_end:; response_count:1; response_revision:2002; }","duration":"824.854133ms","start":"2026-01-30T16:29:06.233879Z","end":"2026-01-30T16:29:07.058733Z","steps":["trace[241788532] 'agreement among raft nodes before linearized reading' (duration: 821.805221ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:29:07.058799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:29:06.233873Z","time spent":"824.916816ms","remote":"127.0.0.1:49344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":830,"request content":"key:\"/registry/rolebindings/kube-system/system::leader-locking-kube-scheduler\" "} {"level":"warn","ts":"2026-01-30T16:29:07.055831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.851998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-30T16:29:07.058911Z","caller":"traceutil/trace.go:171","msg":"trace[1481359618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2002; }","duration":"831.940571ms","start":"2026-01-30T16:29:06.226963Z","end":"2026-01-30T16:29:07.058904Z","steps":["trace[1481359618] 'agreement among raft nodes before linearized reading' (duration: 828.759886ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:29:07.058949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:29:06.226958Z","time spent":"831.982513ms","remote":"127.0.0.1:49252","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-01-30T16:29:09.449014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.958412ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:29:09.44912Z","caller":"traceutil/trace.go:171","msg":"trace[1403383221] linearizableReadLoop","detail":"{readStateIndex:2108; appliedIndex:2107; }","duration":"209.489319ms","start":"2026-01-30T16:29:09.239613Z","end":"2026-01-30T16:29:09.449102Z","steps":["trace[1403383221] 'read index received' (duration: 73.233971ms)","trace[1403383221] 'applied index is now lower than readState.Index' (duration: 136.254408ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:29:09.449197Z","caller":"traceutil/trace.go:171","msg":"trace[1565276497] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"242.259917ms","start":"2026-01-30T16:29:09.206927Z","end":"2026-01-30T16:29:09.449187Z","steps":["trace[1565276497] 'process raft request' (duration: 105.992478ms)","trace[1565276497] 'compare' (duration: 135.817579ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:29:09.449341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.725765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-5frxu3\" ","response":"range_response_count:1 size:692"} {"level":"info","ts":"2026-01-30T16:29:09.449364Z","caller":"traceutil/trace.go:171","msg":"trace[1391382859] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-5frxu3; range_end:; response_count:1; response_revision:2017; }","duration":"209.787456ms","start":"2026-01-30T16:29:09.239566Z","end":"2026-01-30T16:29:09.449354Z","steps":["trace[1391382859] 'agreement among raft nodes before linearized reading' (duration: 209.727595ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:29:09.449548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.001816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-vx58z9\" ","response":"range_response_count:1 size:540"} {"level":"info","ts":"2026-01-30T16:29:09.449621Z","caller":"traceutil/trace.go:171","msg":"trace[647325778] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-vx58z9; range_end:; response_count:1; response_revision:2017; }","duration":"169.146799ms","start":"2026-01-30T16:29:09.280457Z","end":"2026-01-30T16:29:09.449603Z","steps":["trace[647325778] 'agreement among raft nodes before linearized reading' (duration: 169.024136ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:29:11.311515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.709403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-01-30T16:29:11.311579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.440465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-01-30T16:29:11.311727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.795428ms","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:532"} {"level":"info","ts":"2026-01-30T16:29:11.311718Z","caller":"traceutil/trace.go:171","msg":"trace[1408044202] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:2020; }","duration":"102.616729ms","start":"2026-01-30T16:29:11.209078Z","end":"2026-01-30T16:29:11.311695Z","steps":["trace[1408044202] 'range keys from in-memory index tree' (duration: 102.348332ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:29:11.311758Z","caller":"traceutil/trace.go:171","msg":"trace[1438007288] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2020; }","duration":"106.85942ms","start":"2026-01-30T16:29:11.20489Z","end":"2026-01-30T16:29:11.311749Z","steps":["trace[1438007288] 'range keys from in-memory index tree' (duration: 106.679165ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:29:11.312283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.07277ms","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-01-30T16:29:11.312318Z","caller":"traceutil/trace.go:171","msg":"trace[720368566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2020; }","duration":"103.117941ms","start":"2026-01-30T16:29:11.20919Z","end":"2026-01-30T16:29:11.312307Z","steps":["trace[720368566] 'range keys from in-memory index tree' (duration: 102.907046ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:29:11.31164Z","caller":"traceutil/trace.go:171","msg":"trace[1392781671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2020; }","duration":"105.820075ms","start":"2026-01-30T16:29:11.205776Z","end":"2026-01-30T16:29:11.311596Z","steps":["trace[1392781671] 'range keys from in-memory index tree' (duration: 105.518718ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:29:11.5515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.607346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-01-30T16:29:11.551584Z","caller":"traceutil/trace.go:171","msg":"trace[1906461198] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:2023; }","duration":"128.73119ms","start":"2026-01-30T16:29:11.422835Z","end":"2026-01-30T16:29:11.551566Z","steps":["trace[1906461198] 'range keys from in-memory index tree' (duration: 128.513755ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:29:13.527933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.772446ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:29:13.529251Z","caller":"traceutil/trace.go:171","msg":"trace[1433427296] linearizableReadLoop","detail":"{readStateIndex:2122; appliedIndex:2120; }","duration":"168.165757ms","start":"2026-01-30T16:29:13.361061Z","end":"2026-01-30T16:29:13.529226Z","steps":["trace[1433427296] 'read index received' (duration: 27.571904ms)","trace[1433427296] 'applied index is now lower than readState.Index' (duration: 140.590883ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:29:13.529272Z","caller":"traceutil/trace.go:171","msg":"trace[1039990154] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"168.899505ms","start":"2026-01-30T16:29:13.360343Z","end":"2026-01-30T16:29:13.529243Z","steps":["trace[1039990154] 'process raft request' (duration: 63.726525ms)","trace[1039990154] 'compare' (duration: 103.611313ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:29:13.529823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.75563ms","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:532"} {"level":"info","ts":"2026-01-30T16:29:13.529881Z","caller":"traceutil/trace.go:171","msg":"trace[1492322110] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2030; }","duration":"168.833273ms","start":"2026-01-30T16:29:13.361031Z","end":"2026-01-30T16:29:13.529864Z","steps":["trace[1492322110] 'agreement among raft nodes before linearized reading' (duration: 168.632668ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:29:14.62645Z","caller":"traceutil/trace.go:171","msg":"trace[1917639027] transaction","detail":"{read_only:false; response_revision:2036; number_of_response:1; }","duration":"148.809007ms","start":"2026-01-30T16:29:14.477613Z","end":"2026-01-30T16:29:14.626422Z","steps":["trace[1917639027] 'process raft request' (duration: 148.560071ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:29:14.827194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.151955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:134 size:102478"} {"level":"info","ts":"2026-01-30T16:29:14.827297Z","caller":"traceutil/trace.go:171","msg":"trace[1245413341] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:134; response_revision:2036; }","duration":"152.310579ms","start":"2026-01-30T16:29:14.674966Z","end":"2026-01-30T16:29:14.827277Z","steps":["trace[1245413341] 'range keys from in-memory index tree' (duration: 151.276245ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:29:14.827834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.434146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:134 size:102478"} {"level":"info","ts":"2026-01-30T16:29:14.827924Z","caller":"traceutil/trace.go:171","msg":"trace[1355582552] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:134; response_revision:2036; }","duration":"150.563849ms","start":"2026-01-30T16:29:14.677342Z","end":"2026-01-30T16:29:14.827906Z","steps":["trace[1355582552] 'range keys from in-memory index tree' (duration: 149.77638ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:29:21.489909Z","caller":"traceutil/trace.go:171","msg":"trace[482685995] transaction","detail":"{read_only:false; response_revision:2061; number_of_response:1; }","duration":"112.691308ms","start":"2026-01-30T16:29:21.377179Z","end":"2026-01-30T16:29:21.48987Z","steps":["trace[482685995] 'process raft request' (duration: 112.317579ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:29:21.751284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.053126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6bh9w\" ","response":"range_response_count:1 size:18058"} {"level":"info","ts":"2026-01-30T16:29:21.751402Z","caller":"traceutil/trace.go:171","msg":"trace[969443388] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6bh9w; range_end:; response_count:1; response_revision:2062; }","duration":"115.22135ms","start":"2026-01-30T16:29:21.636161Z","end":"2026-01-30T16:29:21.751382Z","steps":["trace[969443388] 'range keys from in-memory index tree' (duration: 114.783258ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:29:21.751745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.996737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-01-30T16:29:21.751782Z","caller":"traceutil/trace.go:171","msg":"trace[191993045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2062; }","duration":"185.070569ms","start":"2026-01-30T16:29:21.566702Z","end":"2026-01-30T16:29:21.751773Z","steps":["trace[191993045] 'range keys from in-memory index tree' (duration: 184.875914ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:29:22.783793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.545116ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-01-30T16:29:22.783957Z","caller":"traceutil/trace.go:171","msg":"trace[1205492748] linearizableReadLoop","detail":"{readStateIndex:2162; appliedIndex:2161; }","duration":"172.275684ms","start":"2026-01-30T16:29:22.611652Z","end":"2026-01-30T16:29:22.783928Z","steps":["trace[1205492748] 'read index received' (duration: 40.621µs)","trace[1205492748] 'applied index is now lower than readState.Index' (duration: 172.233443ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:29:22.784166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.498539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6bh9w\" ","response":"range_response_count:1 size:18058"} {"level":"info","ts":"2026-01-30T16:29:22.784196Z","caller":"traceutil/trace.go:171","msg":"trace[38775814] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6bh9w; range_end:; response_count:1; response_revision:2068; }","duration":"172.566001ms","start":"2026-01-30T16:29:22.611617Z","end":"2026-01-30T16:29:22.784183Z","steps":["trace[38775814] 'agreement among raft nodes before linearized reading' (duration: 172.375246ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:29:23.795475Z","caller":"traceutil/trace.go:171","msg":"trace[1091927870] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"100.713594ms","start":"2026-01-30T16:29:23.694735Z","end":"2026-01-30T16:29:23.795449Z","steps":["trace[1091927870] 'process raft request' (duration: 100.472488ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:29:23.969132Z","caller":"traceutil/trace.go:171","msg":"trace[566308588] transaction","detail":"{read_only:false; response_revision:2074; number_of_response:1; }","duration":"105.64777ms","start":"2026-01-30T16:29:23.863457Z","end":"2026-01-30T16:29:23.969105Z","steps":["trace[566308588] 'process raft request' (duration: 105.578718ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:29:23.969675Z","caller":"traceutil/trace.go:171","msg":"trace[1784207728] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"110.739171ms","start":"2026-01-30T16:29:23.858866Z","end":"2026-01-30T16:29:23.969606Z","steps":["trace[1784207728] 'process raft request' (duration: 54.636798ms)","trace[1784207728] 'compare' (duration: 55.355466ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:29:24.224497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.891088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-j65sd\" ","response":"range_response_count:1 size:4844"} {"level":"info","ts":"2026-01-30T16:29:24.224604Z","caller":"traceutil/trace.go:171","msg":"trace[1800490269] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-j65sd; range_end:; response_count:1; response_revision:2075; }","duration":"133.050801ms","start":"2026-01-30T16:29:24.091529Z","end":"2026-01-30T16:29:24.224579Z","steps":["trace[1800490269] 'range keys from in-memory index tree' (duration: 132.663863ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:29:24.76027Z","caller":"traceutil/trace.go:171","msg":"trace[454827856] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"113.460717ms","start":"2026-01-30T16:29:24.646789Z","end":"2026-01-30T16:29:24.76025Z","steps":["trace[454827856] 'process raft request' (duration: 113.306553ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:29:24.959471Z","caller":"traceutil/trace.go:171","msg":"trace[150264265] linearizableReadLoop","detail":"{readStateIndex:2171; appliedIndex:2170; }","duration":"248.337643ms","start":"2026-01-30T16:29:24.711106Z","end":"2026-01-30T16:29:24.959443Z","steps":["trace[150264265] 'read index received' (duration: 49.514637ms)","trace[150264265] 'applied index is now lower than readState.Index' (duration: 198.821916ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:29:24.959855Z","caller":"traceutil/trace.go:171","msg":"trace[1544058004] transaction","detail":"{read_only:false; response_revision:2077; number_of_response:1; }","duration":"286.96189ms","start":"2026-01-30T16:29:24.672878Z","end":"2026-01-30T16:29:24.95984Z","steps":["trace[1544058004] 'process raft request' (duration: 286.417607ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:29:24.960213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.197857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:29:24.960259Z","caller":"traceutil/trace.go:171","msg":"trace[1548151602] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2077; }","duration":"229.273069ms","start":"2026-01-30T16:29:24.730974Z","end":"2026-01-30T16:29:24.960247Z","steps":["trace[1548151602] 'agreement among raft nodes before linearized reading' (duration: 229.194818ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:29:24.960236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.119641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-mkvdz\" ","response":"range_response_count:1 size:4847"} {"level":"info","ts":"2026-01-30T16:29:24.960659Z","caller":"traceutil/trace.go:171","msg":"trace[1057375640] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-mkvdz; range_end:; response_count:1; response_revision:2077; }","duration":"249.583412ms","start":"2026-01-30T16:29:24.711062Z","end":"2026-01-30T16:29:24.960645Z","steps":["trace[1057375640] 'agreement among raft nodes before linearized reading' (duration: 249.028409ms)"],"step_count":1}