{"level":"warn","ts":"2026-02-06T20:54:50.323409Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-06T20:54:50.323492Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.207: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.207:2380","--initial-cluster=instance=https://162.253.55.207:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.207:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.207:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-06T20:54:50.323569Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-06T20:54:50.323581Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-02-06T20:54:50.323611Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-06T20:54:50.324256Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"]} {"level":"info","ts":"2026-02-06T20:54:50.324344Z","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.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.207:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-06T20:54:50.333686Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.094724ms"} {"level":"info","ts":"2026-02-06T20:54:50.360442Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6f1fcbe50562a63a","cluster-id":"a9b9f04f4e8a9fba"} {"level":"info","ts":"2026-02-06T20:54:50.360511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=()"} {"level":"info","ts":"2026-02-06T20:54:50.360534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 0"} {"level":"info","ts":"2026-02-06T20:54:50.360544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6f1fcbe50562a63a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-06T20:54:50.360569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 1"} {"level":"info","ts":"2026-02-06T20:54:50.360659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"warn","ts":"2026-02-06T20:54:50.37073Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-06T20:54:50.374164Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-06T20:54:50.377211Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-06T20:54:50.382381Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6f1fcbe50562a63a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-06T20:54:50.384405Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6f1fcbe50562a63a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-06T20:54:50.38451Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-06T20:54:50.384552Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-06T20:54:50.384562Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-06T20:54:50.385793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"info","ts":"2026-02-06T20:54:50.38586Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","added-peer-id":"6f1fcbe50562a63a","added-peer-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-02-06T20:54:50.404337Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-06T20:54:50.404505Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6f1fcbe50562a63a","initial-advertise-peer-urls":["https://162.253.55.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-06T20:54:50.404526Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-06T20:54:50.404588Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-02-06T20:54:50.4046Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-02-06T20:54:51.163443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a is starting a new election at term 1"} {"level":"info","ts":"2026-02-06T20:54:51.163491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became pre-candidate at term 1"} {"level":"info","ts":"2026-02-06T20:54:51.163506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgPreVoteResp from 6f1fcbe50562a63a at term 1"} {"level":"info","ts":"2026-02-06T20:54:51.163519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became candidate at term 2"} {"level":"info","ts":"2026-02-06T20:54:51.163524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgVoteResp from 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-02-06T20:54:51.163533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became leader at term 2"} {"level":"info","ts":"2026-02-06T20:54:51.16354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6f1fcbe50562a63a elected leader 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-02-06T20:54:51.165663Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T20:54:51.167427Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6f1fcbe50562a63a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.207:2379]}","request-path":"/0/members/6f1fcbe50562a63a/attributes","cluster-id":"a9b9f04f4e8a9fba","publish-timeout":"7s"} {"level":"info","ts":"2026-02-06T20:54:51.168522Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T20:54:51.168621Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T20:54:51.168674Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T20:54:51.168689Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T20:54:51.169035Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T20:54:51.170424Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-06T20:54:51.172925Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.207:2379"} {"level":"info","ts":"2026-02-06T20:54:51.180422Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-06T20:54:51.180444Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-06T20:55:08.829781Z","caller":"traceutil/trace.go:171","msg":"trace[146872573] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"110.852756ms","start":"2026-02-06T20:55:08.718911Z","end":"2026-02-06T20:55:08.829764Z","steps":["trace[146872573] 'process raft request' (duration: 110.744203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:13.562697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.243955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-06T20:55:13.562757Z","caller":"traceutil/trace.go:171","msg":"trace[1637886307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:459; }","duration":"590.346878ms","start":"2026-02-06T20:55:12.972398Z","end":"2026-02-06T20:55:13.562745Z","steps":["trace[1637886307] 'range keys from in-memory index tree' (duration: 590.165733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:13.562779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:12.972381Z","time spent":"590.392829ms","remote":"127.0.0.1:33470","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":"info","ts":"2026-02-06T20:55:13.905713Z","caller":"traceutil/trace.go:171","msg":"trace[629261700] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"481.103222ms","start":"2026-02-06T20:55:13.424595Z","end":"2026-02-06T20:55:13.905699Z","steps":["trace[629261700] 'process raft request' (duration: 481.0269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:13.906095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:13.424579Z","time spent":"481.179054ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:55:14.007552Z","caller":"traceutil/trace.go:171","msg":"trace[1139344679] linearizableReadLoop","detail":"{readStateIndex:474; appliedIndex:473; }","duration":"180.506465ms","start":"2026-02-06T20:55:13.827032Z","end":"2026-02-06T20:55:14.007539Z","steps":["trace[1139344679] 'read index received' (duration: 79.281406ms)","trace[1139344679] 'applied index is now lower than readState.Index' (duration: 101.224499ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:55:14.007618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.574347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:55:14.007636Z","caller":"traceutil/trace.go:171","msg":"trace[756028244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:461; }","duration":"180.627638ms","start":"2026-02-06T20:55:13.827003Z","end":"2026-02-06T20:55:14.007631Z","steps":["trace[756028244] 'agreement among raft nodes before linearized reading' (duration: 180.587567ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:55:14.007753Z","caller":"traceutil/trace.go:171","msg":"trace[2133491929] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"439.428073ms","start":"2026-02-06T20:55:13.56831Z","end":"2026-02-06T20:55:14.007738Z","steps":["trace[2133491929] 'process raft request' (duration: 436.361604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:14.007842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:13.568296Z","time spent":"439.494585ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:55:19.204578Z","caller":"traceutil/trace.go:171","msg":"trace[1598504999] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"262.340446ms","start":"2026-02-06T20:55:18.941407Z","end":"2026-02-06T20:55:19.203748Z","steps":["trace[1598504999] 'process raft request' (duration: 262.211343ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:55:20.838199Z","caller":"traceutil/trace.go:171","msg":"trace[1318945774] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"520.942955ms","start":"2026-02-06T20:55:20.317243Z","end":"2026-02-06T20:55:20.838186Z","steps":["trace[1318945774] 'process raft request' (duration: 520.635758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:20.838282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:20.31723Z","time spent":"521.008528ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:55:21.454179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.819247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-06T20:55:21.45425Z","caller":"traceutil/trace.go:171","msg":"trace[592723630] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:512; }","duration":"246.92255ms","start":"2026-02-06T20:55:21.207308Z","end":"2026-02-06T20:55:21.454231Z","steps":["trace[592723630] 'range keys from in-memory index tree' (duration: 246.701525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:22.329457Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057909258878031,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-06T20:55:22.518173Z","caller":"traceutil/trace.go:171","msg":"trace[825360076] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:527; }","duration":"688.719564ms","start":"2026-02-06T20:55:21.82943Z","end":"2026-02-06T20:55:22.518149Z","steps":["trace[825360076] 'read index received' (duration: 688.544819ms)","trace[825360076] 'applied index is now lower than readState.Index' (duration: 173.885µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:55:22.51847Z","caller":"traceutil/trace.go:171","msg":"trace[338002559] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"1.060592241s","start":"2026-02-06T20:55:21.457865Z","end":"2026-02-06T20:55:22.518457Z","steps":["trace[338002559] 'process raft request' (duration: 1.060164751s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:22.518579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:21.457847Z","time spent":"1.060662743s","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:55:22.518867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.14319ms","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-02-06T20:55:22.518987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.555475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:55:22.519036Z","caller":"traceutil/trace.go:171","msg":"trace[474488843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"689.626486ms","start":"2026-02-06T20:55:21.829396Z","end":"2026-02-06T20:55:22.519022Z","steps":["trace[474488843] 'agreement among raft nodes before linearized reading' (duration: 689.557454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:22.519071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:21.829382Z","time spent":"689.683328ms","remote":"127.0.0.1:33200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-06T20:55:22.518995Z","caller":"traceutil/trace.go:171","msg":"trace[1153011147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:513; }","duration":"454.321045ms","start":"2026-02-06T20:55:22.064655Z","end":"2026-02-06T20:55:22.518977Z","steps":["trace[1153011147] 'agreement among raft nodes before linearized reading' (duration: 454.065248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:22.519264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:22.064616Z","time spent":"454.634492ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-06T20:55:22.519363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.100606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-06T20:55:22.519404Z","caller":"traceutil/trace.go:171","msg":"trace[1174419303] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:513; }","duration":"309.141837ms","start":"2026-02-06T20:55:22.210254Z","end":"2026-02-06T20:55:22.519395Z","steps":["trace[1174419303] 'agreement among raft nodes before linearized reading' (duration: 309.021694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:22.519432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:22.210172Z","time spent":"309.25424ms","remote":"127.0.0.1:33470","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-02-06T20:55:22.919482Z","caller":"traceutil/trace.go:171","msg":"trace[1109587608] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"391.376239ms","start":"2026-02-06T20:55:22.528087Z","end":"2026-02-06T20:55:22.919463Z","steps":["trace[1109587608] 'process raft request' (duration: 373.835419ms)","trace[1109587608] 'compare' (duration: 17.481339ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:55:22.919585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:22.528072Z","time spent":"391.458321ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:55:22.923775Z","caller":"traceutil/trace.go:171","msg":"trace[1225760597] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"395.485535ms","start":"2026-02-06T20:55:22.528279Z","end":"2026-02-06T20:55:22.923764Z","steps":["trace[1225760597] 'process raft request' (duration: 394.815847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:22.923828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:22.528273Z","time spent":"395.527156ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:55:28.784329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.576665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T20:55:28.784459Z","caller":"traceutil/trace.go:171","msg":"trace[50931627] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:545; }","duration":"145.627979ms","start":"2026-02-06T20:55:28.638815Z","end":"2026-02-06T20:55:28.784443Z","steps":["trace[50931627] 'read index received' (duration: 26.491µs)","trace[50931627] 'applied index is now lower than readState.Index' (duration: 145.600168ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:55:28.784485Z","caller":"traceutil/trace.go:171","msg":"trace[386149973] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"239.686995ms","start":"2026-02-06T20:55:28.544767Z","end":"2026-02-06T20:55:28.784454Z","steps":["trace[386149973] 'process raft request' (duration: 30.712788ms)","trace[386149973] 'compare' (duration: 207.301833ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:55:28.784588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.778433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-06T20:55:28.784617Z","caller":"traceutil/trace.go:171","msg":"trace[1072486806] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:530; }","duration":"145.975858ms","start":"2026-02-06T20:55:28.638631Z","end":"2026-02-06T20:55:28.784607Z","steps":["trace[1072486806] 'agreement among raft nodes before linearized reading' (duration: 145.877406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:29.363907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.714274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-06T20:55:29.364Z","caller":"traceutil/trace.go:171","msg":"trace[1290545803] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:530; }","duration":"424.843907ms","start":"2026-02-06T20:55:28.939122Z","end":"2026-02-06T20:55:29.363965Z","steps":["trace[1290545803] 'range keys from in-memory index tree' (duration: 424.458897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:29.364055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:28.939089Z","time spent":"424.956ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-06T20:55:29.364348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.034712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-06T20:55:29.364414Z","caller":"traceutil/trace.go:171","msg":"trace[1785366084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:530; }","duration":"407.127304ms","start":"2026-02-06T20:55:28.957278Z","end":"2026-02-06T20:55:29.364405Z","steps":["trace[1785366084] 'range keys from in-memory index tree' (duration: 406.893998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:29.364442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:28.957237Z","time spent":"407.197846ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-06T20:55:29.364801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.836918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-06T20:55:29.364868Z","caller":"traceutil/trace.go:171","msg":"trace[1868653828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:530; }","duration":"403.91687ms","start":"2026-02-06T20:55:28.960934Z","end":"2026-02-06T20:55:29.36485Z","steps":["trace[1868653828] 'range keys from in-memory index tree' (duration: 403.631823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:29.364911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:28.960902Z","time spent":"403.996993ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-06T20:55:29.365091Z","caller":"traceutil/trace.go:171","msg":"trace[2031264824] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"197.981844ms","start":"2026-02-06T20:55:29.167096Z","end":"2026-02-06T20:55:29.365078Z","steps":["trace[2031264824] 'process raft request' (duration: 180.818684ms)","trace[2031264824] 'compare' (duration: 15.755464ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:55:30.149472Z","caller":"traceutil/trace.go:171","msg":"trace[464080237] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"774.913706ms","start":"2026-02-06T20:55:29.374528Z","end":"2026-02-06T20:55:30.149442Z","steps":["trace[464080237] 'process raft request' (duration: 754.708107ms)","trace[464080237] 'compare' (duration: 20.106646ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:55:30.149623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:29.374507Z","time spent":"775.035489ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:55:30.162061Z","caller":"traceutil/trace.go:171","msg":"trace[647425880] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"785.339134ms","start":"2026-02-06T20:55:29.376693Z","end":"2026-02-06T20:55:30.162032Z","steps":["trace[647425880] 'process raft request' (duration: 784.213575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:30.162207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:29.37668Z","time spent":"785.449096ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:55:30.162272Z","caller":"traceutil/trace.go:171","msg":"trace[2012438158] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"785.512478ms","start":"2026-02-06T20:55:29.376741Z","end":"2026-02-06T20:55:30.162253Z","steps":["trace[2012438158] 'process raft request' (duration: 784.534923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:30.162372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:29.376738Z","time spent":"785.58778ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:55:30.237214Z","caller":"traceutil/trace.go:171","msg":"trace[485305953] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:547; }","duration":"407.400099ms","start":"2026-02-06T20:55:29.829793Z","end":"2026-02-06T20:55:30.237193Z","steps":["trace[485305953] 'read index received' (duration: 299.459889ms)","trace[485305953] 'applied index is now lower than readState.Index' (duration: 107.93927ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:55:30.237376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.560853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:55:30.237468Z","caller":"traceutil/trace.go:171","msg":"trace[427036820] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"407.693857ms","start":"2026-02-06T20:55:29.82975Z","end":"2026-02-06T20:55:30.237444Z","steps":["trace[427036820] 'agreement among raft nodes before linearized reading' (duration: 407.559103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:30.23751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:29.829723Z","time spent":"407.778579ms","remote":"127.0.0.1:33200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T20:55:31.290198Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057909258878089,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T20:55:31.790994Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057909258878089,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T20:55:32.200796Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.612023629s","expected-duration":"1s"} {"level":"info","ts":"2026-02-06T20:55:32.201164Z","caller":"traceutil/trace.go:171","msg":"trace[946413082] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:551; }","duration":"1.411266585s","start":"2026-02-06T20:55:30.789879Z","end":"2026-02-06T20:55:32.201146Z","steps":["trace[946413082] 'read index received' (duration: 1.41104134s)","trace[946413082] 'applied index is now lower than readState.Index' (duration: 224.085µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:55:32.201413Z","caller":"traceutil/trace.go:171","msg":"trace[1121957809] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.938459782s","start":"2026-02-06T20:55:30.262941Z","end":"2026-02-06T20:55:32.201401Z","steps":["trace[1121957809] 'process raft request' (duration: 1.938066492s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:32.201522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:30.262921Z","time spent":"1.938529223s","remote":"127.0.0.1:33392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:55:32.201773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.411898031s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-06T20:55:32.201811Z","caller":"traceutil/trace.go:171","msg":"trace[1295510512] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:535; }","duration":"1.411963723s","start":"2026-02-06T20:55:30.789835Z","end":"2026-02-06T20:55:32.201799Z","steps":["trace[1295510512] 'agreement among raft nodes before linearized reading' (duration: 1.41184242s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:32.201872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:30.789817Z","time spent":"1.412046365s","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-06T20:55:32.202074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.371954736s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:55:32.202109Z","caller":"traceutil/trace.go:171","msg":"trace[235438645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"1.372060219s","start":"2026-02-06T20:55:30.83004Z","end":"2026-02-06T20:55:32.2021Z","steps":["trace[235438645] 'agreement among raft nodes before linearized reading' (duration: 1.372007267s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:32.202136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:30.829998Z","time spent":"1.37213252s","remote":"127.0.0.1:33200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T20:55:32.50578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.029822ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:55:32.505998Z","caller":"traceutil/trace.go:171","msg":"trace[1258550502] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:535; }","duration":"143.438002ms","start":"2026-02-06T20:55:32.362532Z","end":"2026-02-06T20:55:32.50597Z","steps":["trace[1258550502] 'range keys from in-memory index tree' (duration: 143.002271ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:55:32.508924Z","caller":"traceutil/trace.go:171","msg":"trace[1897334692] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:552; }","duration":"286.279631ms","start":"2026-02-06T20:55:32.222629Z","end":"2026-02-06T20:55:32.508909Z","steps":["trace[1897334692] 'read index received' (duration: 212.813265ms)","trace[1897334692] 'applied index is now lower than readState.Index' (duration: 73.465216ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:55:32.50919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.547537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:55:32.509248Z","caller":"traceutil/trace.go:171","msg":"trace[1349761118] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"286.634279ms","start":"2026-02-06T20:55:32.222603Z","end":"2026-02-06T20:55:32.509237Z","steps":["trace[1349761118] 'agreement among raft nodes before linearized reading' (duration: 286.380853ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:55:32.509764Z","caller":"traceutil/trace.go:171","msg":"trace[443878134] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"292.667754ms","start":"2026-02-06T20:55:32.21708Z","end":"2026-02-06T20:55:32.509748Z","steps":["trace[443878134] 'process raft request' (duration: 218.416278ms)","trace[443878134] 'compare' (duration: 72.728938ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:55:32.51074Z","caller":"traceutil/trace.go:171","msg":"trace[466481481] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"293.418524ms","start":"2026-02-06T20:55:32.21731Z","end":"2026-02-06T20:55:32.510728Z","steps":["trace[466481481] 'process raft request' (duration: 291.353891ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:55:32.510902Z","caller":"traceutil/trace.go:171","msg":"trace[289987077] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"293.416574ms","start":"2026-02-06T20:55:32.217457Z","end":"2026-02-06T20:55:32.510874Z","steps":["trace[289987077] 'process raft request' (duration: 291.343271ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:55:32.511129Z","caller":"traceutil/trace.go:171","msg":"trace[1188834464] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"289.3602ms","start":"2026-02-06T20:55:32.221758Z","end":"2026-02-06T20:55:32.511118Z","steps":["trace[1188834464] 'process raft request' (duration: 287.088281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:34.854836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.445273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-06T20:55:34.855349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.082603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-06T20:55:34.855785Z","caller":"traceutil/trace.go:171","msg":"trace[204984797] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:551; }","duration":"268.539954ms","start":"2026-02-06T20:55:34.587224Z","end":"2026-02-06T20:55:34.855764Z","steps":["trace[204984797] 'range keys from in-memory index tree' (duration: 267.912058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:34.857057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.31005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-06T20:55:34.858821Z","caller":"traceutil/trace.go:171","msg":"trace[929449121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:551; }","duration":"339.084617ms","start":"2026-02-06T20:55:34.519721Z","end":"2026-02-06T20:55:34.858806Z","steps":["trace[929449121] 'range keys from in-memory index tree' (duration: 337.236619ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:34.858933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:34.5197Z","time spent":"339.216679ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-06T20:55:34.859194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.616106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-06T20:55:34.859948Z","caller":"traceutil/trace.go:171","msg":"trace[146742653] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:551; }","duration":"341.438186ms","start":"2026-02-06T20:55:34.518499Z","end":"2026-02-06T20:55:34.859937Z","steps":["trace[146742653] 'range keys from in-memory index tree' (duration: 340.531344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:34.862836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:34.518487Z","time spent":"344.336901ms","remote":"127.0.0.1:33470","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":"info","ts":"2026-02-06T20:55:34.859491Z","caller":"traceutil/trace.go:171","msg":"trace[2079735172] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:551; }","duration":"337.790353ms","start":"2026-02-06T20:55:34.517142Z","end":"2026-02-06T20:55:34.854932Z","steps":["trace[2079735172] 'range keys from in-memory index tree' (duration: 337.241908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:34.863059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:34.517119Z","time spent":"345.858009ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-06T20:55:44.861195Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057909258878332,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T20:55:45.008243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.570328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T20:55:45.008304Z","caller":"traceutil/trace.go:171","msg":"trace[1533532661] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"671.06261ms","start":"2026-02-06T20:55:44.337229Z","end":"2026-02-06T20:55:45.008291Z","steps":["trace[1533532661] 'process raft request' (duration: 553.388769ms)","trace[1533532661] 'compare' (duration: 117.458556ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:55:45.008333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:44.337218Z","time spent":"671.102822ms","remote":"127.0.0.1:33254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:55:45.014884Z","caller":"traceutil/trace.go:171","msg":"trace[1975649595] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:678; }","duration":"657.959024ms","start":"2026-02-06T20:55:44.356913Z","end":"2026-02-06T20:55:45.014872Z","steps":["trace[1975649595] 'read index received' (duration: 533.657713ms)","trace[1975649595] 'applied index is now lower than readState.Index' (duration: 124.300641ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:55:45.015086Z","caller":"traceutil/trace.go:171","msg":"trace[192052328] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"303.535303ms","start":"2026-02-06T20:55:44.711544Z","end":"2026-02-06T20:55:45.015079Z","steps":["trace[192052328] 'process raft request' (duration: 303.244206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:45.015145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:44.711527Z","time spent":"303.582205ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:55:45.015303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.387705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-sxbbw\" ","response":"range_response_count:1 size:18250"} {"level":"info","ts":"2026-02-06T20:55:45.015328Z","caller":"traceutil/trace.go:171","msg":"trace[2049939248] range","detail":"{range_begin:/registry/pods/kube-system/cilium-sxbbw; range_end:; response_count:1; response_revision:659; }","duration":"658.434436ms","start":"2026-02-06T20:55:44.356887Z","end":"2026-02-06T20:55:45.015322Z","steps":["trace[2049939248] 'agreement among raft nodes before linearized reading' (duration: 658.360054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:45.015346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:44.356875Z","time spent":"658.467317ms","remote":"127.0.0.1:33392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18273,"request content":"key:\"/registry/pods/kube-system/cilium-sxbbw\" "} {"level":"warn","ts":"2026-02-06T20:55:45.01544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.161921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:55:45.01546Z","caller":"traceutil/trace.go:171","msg":"trace[903011638] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:659; }","duration":"186.209632ms","start":"2026-02-06T20:55:44.829246Z","end":"2026-02-06T20:55:45.015455Z","steps":["trace[903011638] 'agreement among raft nodes before linearized reading' (duration: 186.181041ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:55:45.202778Z","caller":"traceutil/trace.go:171","msg":"trace[1490993912] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"179.416387ms","start":"2026-02-06T20:55:45.023347Z","end":"2026-02-06T20:55:45.202764Z","steps":["trace[1490993912] 'read index received' (duration: 153.785729ms)","trace[1490993912] 'applied index is now lower than readState.Index' (duration: 25.630118ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:55:45.202982Z","caller":"traceutil/trace.go:171","msg":"trace[1299727705] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"180.225617ms","start":"2026-02-06T20:55:45.02275Z","end":"2026-02-06T20:55:45.202976Z","steps":["trace[1299727705] 'process raft request' (duration: 154.376504ms)","trace[1299727705] 'compare' (duration: 25.581656ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:55:45.203104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.742315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-06T20:55:45.20312Z","caller":"traceutil/trace.go:171","msg":"trace[942444400] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:660; }","duration":"179.779327ms","start":"2026-02-06T20:55:45.023336Z","end":"2026-02-06T20:55:45.203115Z","steps":["trace[942444400] 'agreement among raft nodes before linearized reading' (duration: 179.733915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:45.499344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.164981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T20:55:45.499485Z","caller":"traceutil/trace.go:171","msg":"trace[1409680617] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:681; }","duration":"296.657027ms","start":"2026-02-06T20:55:45.202819Z","end":"2026-02-06T20:55:45.499476Z","steps":["trace[1409680617] 'read index received' (duration: 166.26995ms)","trace[1409680617] 'applied index is now lower than readState.Index' (duration: 130.386537ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:55:45.499568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.83341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-06T20:55:45.499586Z","caller":"traceutil/trace.go:171","msg":"trace[1055659880] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:664; }","duration":"370.876832ms","start":"2026-02-06T20:55:45.128704Z","end":"2026-02-06T20:55:45.49958Z","steps":["trace[1055659880] 'agreement among raft nodes before linearized reading' (duration: 370.80183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:45.499607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:45.128688Z","time spent":"370.915493ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-06T20:55:45.499744Z","caller":"traceutil/trace.go:171","msg":"trace[1670249437] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"476.270369ms","start":"2026-02-06T20:55:45.023468Z","end":"2026-02-06T20:55:45.499738Z","steps":["trace[1670249437] 'process raft request' (duration: 345.664606ms)","trace[1670249437] 'compare' (duration: 130.11019ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:55:45.499776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:45.023462Z","time spent":"476.299379ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:55:45.499834Z","caller":"traceutil/trace.go:171","msg":"trace[279672656] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"475.745854ms","start":"2026-02-06T20:55:45.024084Z","end":"2026-02-06T20:55:45.49983Z","steps":["trace[279672656] 'process raft request' (duration: 475.315583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:45.499861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:45.024079Z","time spent":"475.768934ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:55:45.499917Z","caller":"traceutil/trace.go:171","msg":"trace[502381705] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"474.918333ms","start":"2026-02-06T20:55:45.024995Z","end":"2026-02-06T20:55:45.499913Z","steps":["trace[502381705] 'process raft request' (duration: 474.435021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:45.499938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:45.024989Z","time spent":"474.937343ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:55:45.500042Z","caller":"traceutil/trace.go:171","msg":"trace[1173224121] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"465.618744ms","start":"2026-02-06T20:55:45.034419Z","end":"2026-02-06T20:55:45.500038Z","steps":["trace[1173224121] 'process raft request' (duration: 465.030459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:45.500069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:45.034412Z","time spent":"465.644587ms","remote":"127.0.0.1:33392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18235,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:55:45.89573Z","caller":"traceutil/trace.go:171","msg":"trace[1966579378] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"376.467796ms","start":"2026-02-06T20:55:45.519247Z","end":"2026-02-06T20:55:45.895715Z","steps":["trace[1966579378] 'process raft request' (duration: 376.361184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:45.895826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:45.519223Z","time spent":"376.555429ms","remote":"127.0.0.1:33392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18047,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:55:46.256076Z","caller":"traceutil/trace.go:171","msg":"trace[33416234] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"347.81375ms","start":"2026-02-06T20:55:45.908239Z","end":"2026-02-06T20:55:46.256052Z","steps":["trace[33416234] 'process raft request' (duration: 347.646215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:46.256235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:45.908222Z","time spent":"347.928873ms","remote":"127.0.0.1:33696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14311,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:55:47.337375Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.021740193s","expected-duration":"1s"} {"level":"info","ts":"2026-02-06T20:55:47.337692Z","caller":"traceutil/trace.go:171","msg":"trace[1573105042] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:688; }","duration":"1.198579064s","start":"2026-02-06T20:55:46.13909Z","end":"2026-02-06T20:55:47.337669Z","steps":["trace[1573105042] 'read index received' (duration: 117.732323ms)","trace[1573105042] 'applied index is now lower than readState.Index' (duration: 1.080845791s)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:55:47.338024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.351178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-06T20:55:47.338051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.198942694s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:55:47.33809Z","caller":"traceutil/trace.go:171","msg":"trace[1696709023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:668; }","duration":"1.199037846s","start":"2026-02-06T20:55:46.139041Z","end":"2026-02-06T20:55:47.338079Z","steps":["trace[1696709023] 'agreement among raft nodes before linearized reading' (duration: 1.198960374s)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:55:47.338088Z","caller":"traceutil/trace.go:171","msg":"trace[83891334] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:668; }","duration":"509.463691ms","start":"2026-02-06T20:55:46.828608Z","end":"2026-02-06T20:55:47.338072Z","steps":["trace[83891334] 'agreement among raft nodes before linearized reading' (duration: 509.356048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:47.338124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:46.13902Z","time spent":"1.199096468s","remote":"127.0.0.1:33200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T20:55:47.338127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:46.828589Z","time spent":"509.529992ms","remote":"127.0.0.1:33212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-06T20:55:47.338344Z","caller":"traceutil/trace.go:171","msg":"trace[658603118] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"1.427963774s","start":"2026-02-06T20:55:45.910369Z","end":"2026-02-06T20:55:47.338333Z","steps":["trace[658603118] 'process raft request' (duration: 1.427147782s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:47.33841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.357939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-06T20:55:47.338421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:45.910363Z","time spent":"1.428006625s","remote":"127.0.0.1:33390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4086,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:55:47.33844Z","caller":"traceutil/trace.go:171","msg":"trace[1026742149] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:668; }","duration":"132.422551ms","start":"2026-02-06T20:55:47.206008Z","end":"2026-02-06T20:55:47.33843Z","steps":["trace[1026742149] 'agreement among raft nodes before linearized reading' (duration: 132.315708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:47.7746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.152518ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T20:55:47.774924Z","caller":"traceutil/trace.go:171","msg":"trace[1441098495] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:689; }","duration":"269.445979ms","start":"2026-02-06T20:55:47.505434Z","end":"2026-02-06T20:55:47.77488Z","steps":["trace[1441098495] 'read index received' (duration: 165.913291ms)","trace[1441098495] 'applied index is now lower than readState.Index' (duration: 103.531878ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:55:47.775222Z","caller":"traceutil/trace.go:171","msg":"trace[1745393530] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"430.807681ms","start":"2026-02-06T20:55:47.344398Z","end":"2026-02-06T20:55:47.775206Z","steps":["trace[1745393530] 'process raft request' (duration: 326.925934ms)","trace[1745393530] 'compare' (duration: 103.043205ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:55:47.775399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:47.344363Z","time spent":"430.896324ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:55:47.77582Z","caller":"traceutil/trace.go:171","msg":"trace[967437118] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"426.352726ms","start":"2026-02-06T20:55:47.349453Z","end":"2026-02-06T20:55:47.775806Z","steps":["trace[967437118] 'process raft request' (duration: 425.303889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:47.775918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:47.349442Z","time spent":"426.442048ms","remote":"127.0.0.1:33390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4438,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:55:47.776156Z","caller":"traceutil/trace.go:171","msg":"trace[1276641793] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"367.70299ms","start":"2026-02-06T20:55:47.408442Z","end":"2026-02-06T20:55:47.776145Z","steps":["trace[1276641793] 'process raft request' (duration: 366.397157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:47.776406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:47.408429Z","time spent":"367.760322ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:55:47.776709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.273755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-06T20:55:47.77673Z","caller":"traceutil/trace.go:171","msg":"trace[236259786] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:671; }","duration":"271.330537ms","start":"2026-02-06T20:55:47.505394Z","end":"2026-02-06T20:55:47.776725Z","steps":["trace[236259786] 'agreement among raft nodes before linearized reading' (duration: 271.174033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:47.776798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.951588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-06T20:55:47.776812Z","caller":"traceutil/trace.go:171","msg":"trace[452232414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:671; }","duration":"267.9866ms","start":"2026-02-06T20:55:47.508821Z","end":"2026-02-06T20:55:47.776808Z","steps":["trace[452232414] 'agreement among raft nodes before linearized reading' (duration: 267.841536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:47.777138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.921458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-06T20:55:47.777157Z","caller":"traceutil/trace.go:171","msg":"trace[884730324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:671; }","duration":"267.96241ms","start":"2026-02-06T20:55:47.50919Z","end":"2026-02-06T20:55:47.777153Z","steps":["trace[884730324] 'agreement among raft nodes before linearized reading' (duration: 267.915778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:47.777239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.115819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-06T20:55:47.777273Z","caller":"traceutil/trace.go:171","msg":"trace[1428109839] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:671; }","duration":"209.170309ms","start":"2026-02-06T20:55:47.568098Z","end":"2026-02-06T20:55:47.777268Z","steps":["trace[1428109839] 'agreement among raft nodes before linearized reading' (duration: 208.896453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:52.300502Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057909258878434,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-06T20:55:52.376404Z","caller":"traceutil/trace.go:171","msg":"trace[888043851] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:742; }","duration":"576.954163ms","start":"2026-02-06T20:55:51.799435Z","end":"2026-02-06T20:55:52.376389Z","steps":["trace[888043851] 'read index received' (duration: 576.8481ms)","trace[888043851] 'applied index is now lower than readState.Index' (duration: 105.553µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:55:52.376583Z","caller":"traceutil/trace.go:171","msg":"trace[1231368194] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"579.802447ms","start":"2026-02-06T20:55:51.796775Z","end":"2026-02-06T20:55:52.376577Z","steps":["trace[1231368194] 'process raft request' (duration: 579.53682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:52.376652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:51.796764Z","time spent":"579.834957ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:55:52.376782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.345754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-06T20:55:52.376798Z","caller":"traceutil/trace.go:171","msg":"trace[548095905] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:721; }","duration":"577.380284ms","start":"2026-02-06T20:55:51.799413Z","end":"2026-02-06T20:55:52.376793Z","steps":["trace[548095905] 'agreement among raft nodes before linearized reading' (duration: 577.317873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:52.376811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:51.799403Z","time spent":"577.404935ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-06T20:55:52.376888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.984179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-06T20:55:52.376899Z","caller":"traceutil/trace.go:171","msg":"trace[1225234445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:721; }","duration":"569.01351ms","start":"2026-02-06T20:55:51.807882Z","end":"2026-02-06T20:55:52.376895Z","steps":["trace[1225234445] 'agreement among raft nodes before linearized reading' (duration: 568.978009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:52.37691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:51.807872Z","time spent":"569.03486ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-06T20:55:52.37699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.961635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:55:52.377003Z","caller":"traceutil/trace.go:171","msg":"trace[1315656223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:721; }","duration":"550.983546ms","start":"2026-02-06T20:55:51.826016Z","end":"2026-02-06T20:55:52.376999Z","steps":["trace[1315656223] 'agreement among raft nodes before linearized reading' (duration: 550.963626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:52.377015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:51.826011Z","time spent":"551.001747ms","remote":"127.0.0.1:33212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T20:55:52.3771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.003512ms","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-02-06T20:55:52.377111Z","caller":"traceutil/trace.go:171","msg":"trace[1408909720] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:721; }","duration":"559.016732ms","start":"2026-02-06T20:55:51.818091Z","end":"2026-02-06T20:55:52.377108Z","steps":["trace[1408909720] 'agreement among raft nodes before linearized reading' (duration: 558.980381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:52.377122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:51.81806Z","time spent":"559.058904ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-06T20:55:52.377188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.540224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-06T20:55:52.377201Z","caller":"traceutil/trace.go:171","msg":"trace[450074810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:721; }","duration":"564.576305ms","start":"2026-02-06T20:55:51.81262Z","end":"2026-02-06T20:55:52.377196Z","steps":["trace[450074810] 'agreement among raft nodes before linearized reading' (duration: 564.544365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:52.377212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:51.812609Z","time spent":"564.600176ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-06T20:55:53.028731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.966464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T20:55:53.028973Z","caller":"traceutil/trace.go:171","msg":"trace[708240986] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:743; }","duration":"202.53748ms","start":"2026-02-06T20:55:52.82642Z","end":"2026-02-06T20:55:53.028958Z","steps":["trace[708240986] 'read index received' (duration: 106.488834ms)","trace[708240986] 'applied index is now lower than readState.Index' (duration: 96.047396ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:55:53.029045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.620762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:55:53.029071Z","caller":"traceutil/trace.go:171","msg":"trace[2128981940] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:725; }","duration":"202.671574ms","start":"2026-02-06T20:55:52.826389Z","end":"2026-02-06T20:55:53.029061Z","steps":["trace[2128981940] 'agreement among raft nodes before linearized reading' (duration: 202.613622ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:55:53.029137Z","caller":"traceutil/trace.go:171","msg":"trace[480792271] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"646.850198ms","start":"2026-02-06T20:55:52.382264Z","end":"2026-02-06T20:55:53.029114Z","steps":["trace[480792271] 'process raft request' (duration: 217.423233ms)","trace[480792271] 'compare' (duration: 428.804659ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:55:53.029266Z","caller":"traceutil/trace.go:171","msg":"trace[302554028] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"646.791007ms","start":"2026-02-06T20:55:52.382464Z","end":"2026-02-06T20:55:53.029255Z","steps":["trace[302554028] 'process raft request' (duration: 646.353905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:53.029265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:52.382223Z","time spent":"646.988511ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:55:53.02932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:52.382455Z","time spent":"646.834077ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:55:53.029414Z","caller":"traceutil/trace.go:171","msg":"trace[1733011022] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"646.892019ms","start":"2026-02-06T20:55:52.382514Z","end":"2026-02-06T20:55:53.029406Z","steps":["trace[1733011022] 'process raft request' (duration: 646.375166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:53.02946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:52.382506Z","time spent":"646.9298ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:55:53.029531Z","caller":"traceutil/trace.go:171","msg":"trace[1100641132] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"644.897777ms","start":"2026-02-06T20:55:52.384625Z","end":"2026-02-06T20:55:53.029522Z","steps":["trace[1100641132] 'process raft request' (duration: 644.293922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:53.029571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:52.384618Z","time spent":"644.929799ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:55:54.170462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.242259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:55:54.17053Z","caller":"traceutil/trace.go:171","msg":"trace[1017814238] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:725; }","duration":"825.360362ms","start":"2026-02-06T20:55:53.345154Z","end":"2026-02-06T20:55:54.170514Z","steps":["trace[1017814238] 'count revisions from in-memory index tree' (duration: 825.177697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:54.170569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:53.345135Z","time spent":"825.424384ms","remote":"127.0.0.1:33346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-02-06T20:55:54.171076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.29206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:55:54.171112Z","caller":"traceutil/trace.go:171","msg":"trace[982217894] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:725; }","duration":"344.350772ms","start":"2026-02-06T20:55:53.82675Z","end":"2026-02-06T20:55:54.171101Z","steps":["trace[982217894] 'range keys from in-memory index tree' (duration: 344.241369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:54.171139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:53.826736Z","time spent":"344.396883ms","remote":"127.0.0.1:33200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T20:55:56.336292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.913506ms","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-02-06T20:55:56.336416Z","caller":"traceutil/trace.go:171","msg":"trace[230112503] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:732; }","duration":"817.049529ms","start":"2026-02-06T20:55:55.519349Z","end":"2026-02-06T20:55:56.336399Z","steps":["trace[230112503] 'count revisions from in-memory index tree' (duration: 816.827223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:56.336457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:55.519287Z","time spent":"817.159341ms","remote":"127.0.0.1:33734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":100,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-06T20:55:56.336575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.588741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:55:56.336663Z","caller":"traceutil/trace.go:171","msg":"trace[623892817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:732; }","duration":"200.719904ms","start":"2026-02-06T20:55:56.135927Z","end":"2026-02-06T20:55:56.336647Z","steps":["trace[623892817] 'range keys from in-memory index tree' (duration: 200.529888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:56.336828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.274252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:55:56.33686Z","caller":"traceutil/trace.go:171","msg":"trace[910146624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:732; }","duration":"510.304922ms","start":"2026-02-06T20:55:55.826546Z","end":"2026-02-06T20:55:56.336851Z","steps":["trace[910146624] 'range keys from in-memory index tree' (duration: 510.230331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:56.336887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:55.826504Z","time spent":"510.374314ms","remote":"127.0.0.1:33212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-06T20:55:57.101567Z","caller":"traceutil/trace.go:171","msg":"trace[1409375128] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:756; }","duration":"272.437585ms","start":"2026-02-06T20:55:56.829114Z","end":"2026-02-06T20:55:57.101551Z","steps":["trace[1409375128] 'read index received' (duration: 272.323562ms)","trace[1409375128] 'applied index is now lower than readState.Index' (duration: 113.433µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:55:57.101646Z","caller":"traceutil/trace.go:171","msg":"trace[847585726] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"319.062952ms","start":"2026-02-06T20:55:56.782575Z","end":"2026-02-06T20:55:57.101638Z","steps":["trace[847585726] 'process raft request' (duration: 318.870257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:55:57.101711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:55:56.782559Z","time spent":"319.102154ms","remote":"127.0.0.1:33390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4726,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:55:57.101943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.819544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:55:57.101983Z","caller":"traceutil/trace.go:171","msg":"trace[74321010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:734; }","duration":"272.909846ms","start":"2026-02-06T20:55:56.829063Z","end":"2026-02-06T20:55:57.101973Z","steps":["trace[74321010] 'agreement among raft nodes before linearized reading' (duration: 272.853325ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:55:57.325332Z","caller":"traceutil/trace.go:171","msg":"trace[523914822] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"212.230089ms","start":"2026-02-06T20:55:57.113085Z","end":"2026-02-06T20:55:57.325315Z","steps":["trace[523914822] 'process raft request' (duration: 212.187088ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:55:57.32551Z","caller":"traceutil/trace.go:171","msg":"trace[1581543525] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"216.808797ms","start":"2026-02-06T20:55:57.108693Z","end":"2026-02-06T20:55:57.325502Z","steps":["trace[1581543525] 'process raft request' (duration: 180.014642ms)","trace[1581543525] 'compare' (duration: 36.044406ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:55:57.325636Z","caller":"traceutil/trace.go:171","msg":"trace[1861098100] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"216.523769ms","start":"2026-02-06T20:55:57.109105Z","end":"2026-02-06T20:55:57.325629Z","steps":["trace[1861098100] 'process raft request' (duration: 216.125339ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:55:58.535635Z","caller":"traceutil/trace.go:171","msg":"trace[1692335681] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"181.709796ms","start":"2026-02-06T20:55:58.353911Z","end":"2026-02-06T20:55:58.535621Z","steps":["trace[1692335681] 'process raft request' (duration: 181.279815ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:56:02.964876Z","caller":"traceutil/trace.go:171","msg":"trace[1039928856] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"409.957545ms","start":"2026-02-06T20:56:02.554905Z","end":"2026-02-06T20:56:02.964862Z","steps":["trace[1039928856] 'process raft request' (duration: 409.722849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:02.964994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:02.554889Z","time spent":"410.043788ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:56:02.966324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.688635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:56:02.966344Z","caller":"traceutil/trace.go:171","msg":"trace[833954365] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"137.742457ms","start":"2026-02-06T20:56:02.828597Z","end":"2026-02-06T20:56:02.96634Z","steps":["trace[833954365] 'agreement among raft nodes before linearized reading' (duration: 137.707456ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:56:02.966282Z","caller":"traceutil/trace.go:171","msg":"trace[697399490] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:778; }","duration":"136.114536ms","start":"2026-02-06T20:56:02.828639Z","end":"2026-02-06T20:56:02.964753Z","steps":["trace[697399490] 'read index received' (duration: 135.935101ms)","trace[697399490] 'applied index is now lower than readState.Index' (duration: 178.945µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:56:13.412681Z","caller":"traceutil/trace.go:171","msg":"trace[1093533034] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"215.88197ms","start":"2026-02-06T20:56:13.196776Z","end":"2026-02-06T20:56:13.412658Z","steps":["trace[1093533034] 'process raft request' (duration: 215.406408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:14.284201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.264883ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T20:56:14.284349Z","caller":"traceutil/trace.go:171","msg":"trace[1035678243] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"868.04442ms","start":"2026-02-06T20:56:13.416271Z","end":"2026-02-06T20:56:14.284315Z","steps":["trace[1035678243] 'process raft request' (duration: 202.600271ms)","trace[1035678243] 'compare' (duration: 663.240652ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:56:14.284506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:13.416256Z","time spent":"868.209594ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:56:14.329392Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057909258878617,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-06T20:56:14.371365Z","caller":"traceutil/trace.go:171","msg":"trace[148705809] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:811; }","duration":"542.041473ms","start":"2026-02-06T20:56:13.829304Z","end":"2026-02-06T20:56:14.371346Z","steps":["trace[148705809] 'read index received' (duration: 541.874239ms)","trace[148705809] 'applied index is now lower than readState.Index' (duration: 166.524µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:56:14.371916Z","caller":"traceutil/trace.go:171","msg":"trace[604676738] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"952.870639ms","start":"2026-02-06T20:56:13.419024Z","end":"2026-02-06T20:56:14.371894Z","steps":["trace[604676738] 'process raft request' (duration: 952.208152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:14.372139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:13.419008Z","time spent":"953.065844ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:56:14.373467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.149356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:56:14.374152Z","caller":"traceutil/trace.go:171","msg":"trace[494338549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:787; }","duration":"544.881016ms","start":"2026-02-06T20:56:13.829261Z","end":"2026-02-06T20:56:14.374142Z","steps":["trace[494338549] 'agreement among raft nodes before linearized reading' (duration: 544.172497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:14.374247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:13.829242Z","time spent":"544.994618ms","remote":"127.0.0.1:33200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T20:56:14.373734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.472695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.207\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-06T20:56:14.374419Z","caller":"traceutil/trace.go:171","msg":"trace[2036962470] range","detail":"{range_begin:/registry/masterleases/162.253.55.207; range_end:; response_count:1; response_revision:787; }","duration":"181.202884ms","start":"2026-02-06T20:56:14.193148Z","end":"2026-02-06T20:56:14.374351Z","steps":["trace[2036962470] 'agreement among raft nodes before linearized reading' (duration: 178.888825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:14.373776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.227668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T20:56:14.374623Z","caller":"traceutil/trace.go:171","msg":"trace[1130715520] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:787; }","duration":"129.111802ms","start":"2026-02-06T20:56:14.245504Z","end":"2026-02-06T20:56:14.374616Z","steps":["trace[1130715520] 'agreement among raft nodes before linearized reading' (duration: 128.246899ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:56:14.373808Z","caller":"traceutil/trace.go:171","msg":"trace[435014278] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"954.721036ms","start":"2026-02-06T20:56:13.41908Z","end":"2026-02-06T20:56:14.373801Z","steps":["trace[435014278] 'process raft request' (duration: 952.235943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:14.375391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:13.419074Z","time spent":"955.984119ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:56:15.144628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.440488ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-06T20:56:15.14473Z","caller":"traceutil/trace.go:171","msg":"trace[1266173066] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:813; }","duration":"519.60721ms","start":"2026-02-06T20:56:14.625094Z","end":"2026-02-06T20:56:15.144701Z","steps":["trace[1266173066] 'read index received' (duration: 46.10513ms)","trace[1266173066] 'applied index is now lower than readState.Index' (duration: 473.50066ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:56:15.144809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:14.376056Z","time spent":"768.750661ms","remote":"127.0.0.1:33254","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-06T20:56:15.145018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.922747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:56:15.145079Z","caller":"traceutil/trace.go:171","msg":"trace[785060424] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:787; }","duration":"519.990669ms","start":"2026-02-06T20:56:14.625048Z","end":"2026-02-06T20:56:15.145039Z","steps":["trace[785060424] 'agreement among raft nodes before linearized reading' (duration: 519.931428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:15.145114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:14.62503Z","time spent":"520.072431ms","remote":"127.0.0.1:33526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-02-06T20:56:15.145303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.123295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T20:56:15.145372Z","caller":"traceutil/trace.go:171","msg":"trace[1906183593] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:787; }","duration":"325.263988ms","start":"2026-02-06T20:56:14.82009Z","end":"2026-02-06T20:56:15.145354Z","steps":["trace[1906183593] 'agreement among raft nodes before linearized reading' (duration: 325.120525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:15.145414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:14.820063Z","time spent":"325.341681ms","remote":"127.0.0.1:33308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":12,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-02-06T20:56:15.145698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.375272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-02-06T20:56:15.145709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.489319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:56:15.145729Z","caller":"traceutil/trace.go:171","msg":"trace[530192946] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:787; }","duration":"117.439243ms","start":"2026-02-06T20:56:15.02828Z","end":"2026-02-06T20:56:15.145719Z","steps":["trace[530192946] 'agreement among raft nodes before linearized reading' (duration: 117.32124ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:56:15.145751Z","caller":"traceutil/trace.go:171","msg":"trace[378133068] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:787; }","duration":"315.558621ms","start":"2026-02-06T20:56:14.830182Z","end":"2026-02-06T20:56:15.14574Z","steps":["trace[378133068] 'agreement among raft nodes before linearized reading' (duration: 315.495589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:15.14578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:14.830164Z","time spent":"315.610192ms","remote":"127.0.0.1:33200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T20:56:15.512995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.376719ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T20:56:15.51325Z","caller":"traceutil/trace.go:171","msg":"trace[1209145918] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"356.126347ms","start":"2026-02-06T20:56:15.157106Z","end":"2026-02-06T20:56:15.513232Z","steps":["trace[1209145918] 'process raft request' (duration: 355.978604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:15.51332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:15.157091Z","time spent":"356.19438ms","remote":"127.0.0.1:33254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:56:15.51366Z","caller":"traceutil/trace.go:171","msg":"trace[821819594] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"356.936398ms","start":"2026-02-06T20:56:15.156709Z","end":"2026-02-06T20:56:15.513645Z","steps":["trace[821819594] 'process raft request' (duration: 111.8369ms)","trace[821819594] 'compare' (duration: 244.271667ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:56:15.513726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:15.156694Z","time spent":"357.00207ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:56:15.757591Z","caller":"traceutil/trace.go:171","msg":"trace[116921213] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:816; }","duration":"341.883384ms","start":"2026-02-06T20:56:15.415687Z","end":"2026-02-06T20:56:15.75757Z","steps":["trace[116921213] 'read index received' (duration: 314.119685ms)","trace[116921213] 'applied index is now lower than readState.Index' (duration: 27.762669ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:56:15.758011Z","caller":"traceutil/trace.go:171","msg":"trace[1595630635] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"396.85111ms","start":"2026-02-06T20:56:15.361143Z","end":"2026-02-06T20:56:15.757994Z","steps":["trace[1595630635] 'process raft request' (duration: 368.71247ms)","trace[1595630635] 'compare' (duration: 27.162554ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:56:15.758258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:15.361123Z","time spent":"396.929512ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:56:15.758674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.976603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:56:15.75911Z","caller":"traceutil/trace.go:171","msg":"trace[396157086] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:790; }","duration":"343.436124ms","start":"2026-02-06T20:56:15.415658Z","end":"2026-02-06T20:56:15.759094Z","steps":["trace[396157086] 'agreement among raft nodes before linearized reading' (duration: 342.952321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:15.759392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:15.415647Z","time spent":"343.552297ms","remote":"127.0.0.1:33448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-02-06T20:56:15.759732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.032987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-06T20:56:15.759771Z","caller":"traceutil/trace.go:171","msg":"trace[857713987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:790; }","duration":"342.083548ms","start":"2026-02-06T20:56:15.417677Z","end":"2026-02-06T20:56:15.759761Z","steps":["trace[857713987] 'agreement among raft nodes before linearized reading' (duration: 341.962015ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:15.759863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:15.417671Z","time spent":"342.182492ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-06T20:56:15.760775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.903713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-06T20:56:15.760803Z","caller":"traceutil/trace.go:171","msg":"trace[1940211085] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:790; }","duration":"242.952784ms","start":"2026-02-06T20:56:15.517844Z","end":"2026-02-06T20:56:15.760797Z","steps":["trace[1940211085] 'agreement among raft nodes before linearized reading' (duration: 242.902062ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:56:16.233827Z","caller":"traceutil/trace.go:171","msg":"trace[1595826273] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"468.135562ms","start":"2026-02-06T20:56:15.765668Z","end":"2026-02-06T20:56:16.233804Z","steps":["trace[1595826273] 'read index received' (duration: 460.550518ms)","trace[1595826273] 'applied index is now lower than readState.Index' (duration: 7.584134ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:56:16.233949Z","caller":"traceutil/trace.go:171","msg":"trace[671825358] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"468.798659ms","start":"2026-02-06T20:56:15.765129Z","end":"2026-02-06T20:56:16.233928Z","steps":["trace[671825358] 'process raft request' (duration: 461.160494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:16.23399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.310157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-06T20:56:16.234022Z","caller":"traceutil/trace.go:171","msg":"trace[414905812] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:791; }","duration":"468.375699ms","start":"2026-02-06T20:56:15.765637Z","end":"2026-02-06T20:56:16.234013Z","steps":["trace[414905812] 'agreement among raft nodes before linearized reading' (duration: 468.294836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:16.234051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:15.765112Z","time spent":"468.882061ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:56:16.234054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:15.765627Z","time spent":"468.419881ms","remote":"127.0.0.1:33476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-02-06T20:56:16.23426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.405956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:56:16.234285Z","caller":"traceutil/trace.go:171","msg":"trace[1833804766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:791; }","duration":"401.459978ms","start":"2026-02-06T20:56:15.832817Z","end":"2026-02-06T20:56:16.234277Z","steps":["trace[1833804766] 'agreement among raft nodes before linearized reading' (duration: 401.418847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:16.234303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:15.832799Z","time spent":"401.500359ms","remote":"127.0.0.1:33200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T20:56:16.234413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.546652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T20:56:16.234446Z","caller":"traceutil/trace.go:171","msg":"trace[700405992] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:791; }","duration":"387.582023ms","start":"2026-02-06T20:56:15.846855Z","end":"2026-02-06T20:56:16.234437Z","steps":["trace[700405992] 'agreement among raft nodes before linearized reading' (duration: 387.524421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:16.234493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:15.846838Z","time spent":"387.632425ms","remote":"127.0.0.1:33702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-02-06T20:56:27.805818Z","caller":"traceutil/trace.go:171","msg":"trace[1901289004] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"245.284682ms","start":"2026-02-06T20:56:27.560509Z","end":"2026-02-06T20:56:27.805794Z","steps":["trace[1901289004] 'process raft request' (duration: 245.169999ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:56:28.69876Z","caller":"traceutil/trace.go:171","msg":"trace[1111835506] linearizableReadLoop","detail":"{readStateIndex:907; appliedIndex:906; }","duration":"344.083741ms","start":"2026-02-06T20:56:28.354661Z","end":"2026-02-06T20:56:28.698745Z","steps":["trace[1111835506] 'read index received' (duration: 344.018649ms)","trace[1111835506] 'applied index is now lower than readState.Index' (duration: 64.532µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:56:28.698833Z","caller":"traceutil/trace.go:171","msg":"trace[1957661292] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"368.894847ms","start":"2026-02-06T20:56:28.329933Z","end":"2026-02-06T20:56:28.698828Z","steps":["trace[1957661292] 'process raft request' (duration: 368.679451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:28.698913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:28.329923Z","time spent":"368.925787ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:56:28.699065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.401809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-06T20:56:28.699081Z","caller":"traceutil/trace.go:171","msg":"trace[1706376198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:876; }","duration":"344.44079ms","start":"2026-02-06T20:56:28.354635Z","end":"2026-02-06T20:56:28.699076Z","steps":["trace[1706376198] 'agreement among raft nodes before linearized reading' (duration: 344.359038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:28.699095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:28.354622Z","time spent":"344.4699ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-06T20:56:28.884163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.346648ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T20:56:28.884266Z","caller":"traceutil/trace.go:171","msg":"trace[1342248920] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"182.384394ms","start":"2026-02-06T20:56:28.701872Z","end":"2026-02-06T20:56:28.884256Z","steps":["trace[1342248920] 'process raft request' (duration: 182.353733ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:56:28.88427Z","caller":"traceutil/trace.go:171","msg":"trace[297139245] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"402.115242ms","start":"2026-02-06T20:56:28.482122Z","end":"2026-02-06T20:56:28.884238Z","steps":["trace[297139245] 'process raft request' (duration: 263.60751ms)","trace[297139245] 'compare' (duration: 138.268886ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:56:28.884385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:28.482109Z","time spent":"402.198714ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:56:28.884402Z","caller":"traceutil/trace.go:171","msg":"trace[399714465] linearizableReadLoop","detail":"{readStateIndex:908; appliedIndex:907; }","duration":"185.583846ms","start":"2026-02-06T20:56:28.698813Z","end":"2026-02-06T20:56:28.884396Z","steps":["trace[399714465] 'read index received' (duration: 46.860169ms)","trace[399714465] 'applied index is now lower than readState.Index' (duration: 138.723127ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:56:28.884524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.863179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T20:56:28.88454Z","caller":"traceutil/trace.go:171","msg":"trace[695917580] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:878; }","duration":"283.88035ms","start":"2026-02-06T20:56:28.600654Z","end":"2026-02-06T20:56:28.884534Z","steps":["trace[695917580] 'agreement among raft nodes before linearized reading' (duration: 283.843569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:28.884649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.060409ms","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-02-06T20:56:28.884662Z","caller":"traceutil/trace.go:171","msg":"trace[17600233] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:878; }","duration":"425.09401ms","start":"2026-02-06T20:56:28.459564Z","end":"2026-02-06T20:56:28.884658Z","steps":["trace[17600233] 'agreement among raft nodes before linearized reading' (duration: 425.037508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:28.884675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:28.459552Z","time spent":"425.11963ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-06T20:56:28.884811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.88366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-06T20:56:28.884832Z","caller":"traceutil/trace.go:171","msg":"trace[1384815335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:878; }","duration":"443.927742ms","start":"2026-02-06T20:56:28.440898Z","end":"2026-02-06T20:56:28.884826Z","steps":["trace[1384815335] 'agreement among raft nodes before linearized reading' (duration: 443.84606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:28.884856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:28.440884Z","time spent":"443.969073ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-06T20:56:30.56205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.51242513s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T20:56:30.562112Z","caller":"traceutil/trace.go:171","msg":"trace[923519833] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"1.673224701s","start":"2026-02-06T20:56:28.888877Z","end":"2026-02-06T20:56:30.562102Z","steps":["trace[923519833] 'process raft request' (duration: 160.696759ms)","trace[923519833] 'compare' (duration: 1.511830335s)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:56:30.562142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:28.888868Z","time spent":"1.673261172s","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:56:30.59338Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.543802132s","expected-duration":"1s"} {"level":"info","ts":"2026-02-06T20:56:30.593525Z","caller":"traceutil/trace.go:171","msg":"trace[395992621] linearizableReadLoop","detail":"{readStateIndex:911; appliedIndex:909; }","duration":"1.640618997s","start":"2026-02-06T20:56:28.952896Z","end":"2026-02-06T20:56:30.593515Z","steps":["trace[395992621] 'read index received' (duration: 96.687643ms)","trace[395992621] 'applied index is now lower than readState.Index' (duration: 1.543929614s)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:56:30.593639Z","caller":"traceutil/trace.go:171","msg":"trace[1999105073] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"1.702025047s","start":"2026-02-06T20:56:28.891609Z","end":"2026-02-06T20:56:30.593634Z","steps":["trace[1999105073] 'process raft request' (duration: 1.701853963s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:30.59368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:28.891594Z","time spent":"1.702058348s","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:56:30.593773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.640879304s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T20:56:30.59379Z","caller":"traceutil/trace.go:171","msg":"trace[687717693] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:880; }","duration":"1.640932945s","start":"2026-02-06T20:56:28.952852Z","end":"2026-02-06T20:56:30.593785Z","steps":["trace[687717693] 'agreement among raft nodes before linearized reading' (duration: 1.640896824s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:30.593806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:28.952836Z","time spent":"1.640965896s","remote":"127.0.0.1:33626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-06T20:56:31.17693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.497833ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-06T20:56:31.176994Z","caller":"traceutil/trace.go:171","msg":"trace[1582011677] linearizableReadLoop","detail":"{readStateIndex:912; appliedIndex:911; }","duration":"583.443452ms","start":"2026-02-06T20:56:30.59354Z","end":"2026-02-06T20:56:31.176983Z","steps":["trace[1582011677] 'read index received' (duration: 67.955579ms)","trace[1582011677] 'applied index is now lower than readState.Index' (duration: 515.487233ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:56:31.177128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.368424908s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-06T20:56:31.177142Z","caller":"traceutil/trace.go:171","msg":"trace[381812665] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:880; }","duration":"1.368474739s","start":"2026-02-06T20:56:29.808662Z","end":"2026-02-06T20:56:31.177137Z","steps":["trace[381812665] 'agreement among raft nodes before linearized reading' (duration: 1.368382147s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:31.177163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:29.808632Z","time spent":"1.368525001s","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-06T20:56:31.177331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.502191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-06T20:56:31.177396Z","caller":"traceutil/trace.go:171","msg":"trace[1778495313] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:880; }","duration":"475.600913ms","start":"2026-02-06T20:56:30.701784Z","end":"2026-02-06T20:56:31.177385Z","steps":["trace[1778495313] 'agreement among raft nodes before linearized reading' (duration: 475.439379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:31.177418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:30.701771Z","time spent":"475.642685ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-06T20:56:31.177464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.295708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-06T20:56:31.17749Z","caller":"traceutil/trace.go:171","msg":"trace[280429935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:880; }","duration":"289.34518ms","start":"2026-02-06T20:56:30.888139Z","end":"2026-02-06T20:56:31.177484Z","steps":["trace[280429935] 'agreement among raft nodes before linearized reading' (duration: 289.288218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:31.177534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.76522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:56:31.177553Z","caller":"traceutil/trace.go:171","msg":"trace[618902811] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:880; }","duration":"348.816641ms","start":"2026-02-06T20:56:30.82873Z","end":"2026-02-06T20:56:31.177547Z","steps":["trace[618902811] 'agreement among raft nodes before linearized reading' (duration: 348.790021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:31.177565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:30.828712Z","time spent":"348.851072ms","remote":"127.0.0.1:33200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T20:56:35.735453Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057909258878875,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T20:56:36.237497Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057909258878875,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T20:56:36.407568Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.186609657s","expected-duration":"1s"} {"level":"info","ts":"2026-02-06T20:56:36.407851Z","caller":"traceutil/trace.go:171","msg":"trace[748252768] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"1.186925734s","start":"2026-02-06T20:56:35.220908Z","end":"2026-02-06T20:56:36.407834Z","steps":["trace[748252768] 'process raft request' (duration: 1.186819902s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:36.407937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:35.220899Z","time spent":"1.186978576s","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:56:36.408234Z","caller":"traceutil/trace.go:171","msg":"trace[68028263] linearizableReadLoop","detail":"{readStateIndex:925; appliedIndex:925; }","duration":"1.173178483s","start":"2026-02-06T20:56:35.23505Z","end":"2026-02-06T20:56:36.408228Z","steps":["trace[68028263] 'read index received' (duration: 1.173175643s)","trace[68028263] 'applied index is now lower than readState.Index' (duration: 2.24µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:56:36.408325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.173263605s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-06T20:56:36.40834Z","caller":"traceutil/trace.go:171","msg":"trace[705825506] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:892; }","duration":"1.173296816s","start":"2026-02-06T20:56:35.235038Z","end":"2026-02-06T20:56:36.408335Z","steps":["trace[705825506] 'agreement among raft nodes before linearized reading' (duration: 1.173218164s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:36.408354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:35.235034Z","time spent":"1.173316996s","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-06T20:56:36.931896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.557937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T20:56:36.932001Z","caller":"traceutil/trace.go:171","msg":"trace[1445065701] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"520.160752ms","start":"2026-02-06T20:56:36.411828Z","end":"2026-02-06T20:56:36.931989Z","steps":["trace[1445065701] 'process raft request' (duration: 520.133492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:36.932041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:36.411813Z","time spent":"520.213423ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:56:36.93222Z","caller":"traceutil/trace.go:171","msg":"trace[1304562483] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"662.034749ms","start":"2026-02-06T20:56:36.27018Z","end":"2026-02-06T20:56:36.932214Z","steps":["trace[1304562483] 'process raft request' (duration: 271.102032ms)","trace[1304562483] 'compare' (duration: 390.295621ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:56:36.932251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:36.270159Z","time spent":"662.078011ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:56:36.932313Z","caller":"traceutil/trace.go:171","msg":"trace[862600088] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:925; }","duration":"524.061161ms","start":"2026-02-06T20:56:36.408247Z","end":"2026-02-06T20:56:36.932308Z","steps":["trace[862600088] 'read index received' (duration: 133.046752ms)","trace[862600088] 'applied index is now lower than readState.Index' (duration: 391.013689ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:56:36.932419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.83214ms","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-02-06T20:56:36.932438Z","caller":"traceutil/trace.go:171","msg":"trace[230953033] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:894; }","duration":"879.88276ms","start":"2026-02-06T20:56:36.05255Z","end":"2026-02-06T20:56:36.932433Z","steps":["trace[230953033] 'agreement among raft nodes before linearized reading' (duration: 879.85055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:36.932454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:36.052537Z","time spent":"879.913402ms","remote":"127.0.0.1:33728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-06T20:56:36.932529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104711191s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:56:36.932545Z","caller":"traceutil/trace.go:171","msg":"trace[490035038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:894; }","duration":"1.104756011s","start":"2026-02-06T20:56:35.827785Z","end":"2026-02-06T20:56:36.932541Z","steps":["trace[490035038] 'agreement among raft nodes before linearized reading' (duration: 1.104732841s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:36.932558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:35.827766Z","time spent":"1.104788503s","remote":"127.0.0.1:33200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T20:56:36.932667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.696220378s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-06T20:56:36.932682Z","caller":"traceutil/trace.go:171","msg":"trace[887564041] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:894; }","duration":"1.696260729s","start":"2026-02-06T20:56:35.236418Z","end":"2026-02-06T20:56:36.932678Z","steps":["trace[887564041] 'agreement among raft nodes before linearized reading' (duration: 1.696195827s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:36.932694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:35.236403Z","time spent":"1.69628793s","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-06T20:56:36.932779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.275739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-06T20:56:36.932794Z","caller":"traceutil/trace.go:171","msg":"trace[151934212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:894; }","duration":"309.321239ms","start":"2026-02-06T20:56:36.623469Z","end":"2026-02-06T20:56:36.932791Z","steps":["trace[151934212] 'agreement among raft nodes before linearized reading' (duration: 309.271448ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:36.932811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:36.623455Z","time spent":"309.351441ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-06T20:56:36.932969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.326837ms","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-02-06T20:56:36.932987Z","caller":"traceutil/trace.go:171","msg":"trace[314306240] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:894; }","duration":"172.376887ms","start":"2026-02-06T20:56:36.760606Z","end":"2026-02-06T20:56:36.932983Z","steps":["trace[314306240] 'agreement among raft nodes before linearized reading' (duration: 172.329586ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:56:38.542695Z","caller":"traceutil/trace.go:171","msg":"trace[1551911327] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"121.336413ms","start":"2026-02-06T20:56:38.421341Z","end":"2026-02-06T20:56:38.542678Z","steps":["trace[1551911327] 'process raft request' (duration: 121.247361ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:56:39.10047Z","caller":"traceutil/trace.go:171","msg":"trace[470195114] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:931; }","duration":"141.008665ms","start":"2026-02-06T20:56:38.959441Z","end":"2026-02-06T20:56:39.10045Z","steps":["trace[470195114] 'read index received' (duration: 140.828791ms)","trace[470195114] 'applied index is now lower than readState.Index' (duration: 178.944µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:56:39.100564Z","caller":"traceutil/trace.go:171","msg":"trace[310953351] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"160.039073ms","start":"2026-02-06T20:56:38.940518Z","end":"2026-02-06T20:56:39.100557Z","steps":["trace[310953351] 'process raft request' (duration: 159.784416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:39.100752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.296652ms","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-02-06T20:56:39.100777Z","caller":"traceutil/trace.go:171","msg":"trace[1301820863] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:899; }","duration":"141.349074ms","start":"2026-02-06T20:56:38.959419Z","end":"2026-02-06T20:56:39.100768Z","steps":["trace[1301820863] 'agreement among raft nodes before linearized reading' (duration: 141.248512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:39.101045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.32835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-06T20:56:39.101069Z","caller":"traceutil/trace.go:171","msg":"trace[1106208632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:899; }","duration":"135.362252ms","start":"2026-02-06T20:56:38.965699Z","end":"2026-02-06T20:56:39.101062Z","steps":["trace[1106208632] 'agreement among raft nodes before linearized reading' (duration: 135.2901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:39.101116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.891734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-06T20:56:39.101221Z","caller":"traceutil/trace.go:171","msg":"trace[60695695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:899; }","duration":"136.014278ms","start":"2026-02-06T20:56:38.965185Z","end":"2026-02-06T20:56:39.101199Z","steps":["trace[60695695] 'agreement among raft nodes before linearized reading' (duration: 135.741411ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:56:39.256692Z","caller":"traceutil/trace.go:171","msg":"trace[1153088842] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"142.985727ms","start":"2026-02-06T20:56:39.113691Z","end":"2026-02-06T20:56:39.256676Z","steps":["trace[1153088842] 'process raft request' (duration: 54.77897ms)","trace[1153088842] 'compare' (duration: 87.854638ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:56:39.256795Z","caller":"traceutil/trace.go:171","msg":"trace[335906991] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"141.790007ms","start":"2026-02-06T20:56:39.115001Z","end":"2026-02-06T20:56:39.256791Z","steps":["trace[335906991] 'process raft request' (duration: 141.427317ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:56:39.256848Z","caller":"traceutil/trace.go:171","msg":"trace[2049539334] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"140.751219ms","start":"2026-02-06T20:56:39.116093Z","end":"2026-02-06T20:56:39.256844Z","steps":["trace[2049539334] 'process raft request' (duration: 140.376299ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:56:44.934899Z","caller":"traceutil/trace.go:171","msg":"trace[1478117914] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"361.705411ms","start":"2026-02-06T20:56:44.573172Z","end":"2026-02-06T20:56:44.934877Z","steps":["trace[1478117914] 'process raft request' (duration: 361.589638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:44.93503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:44.573161Z","time spent":"361.799693ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:56:44.935441Z","caller":"traceutil/trace.go:171","msg":"trace[1217588387] linearizableReadLoop","detail":"{readStateIndex:950; appliedIndex:950; }","duration":"108.527486ms","start":"2026-02-06T20:56:44.826901Z","end":"2026-02-06T20:56:44.935429Z","steps":["trace[1217588387] 'read index received' (duration: 108.512275ms)","trace[1217588387] 'applied index is now lower than readState.Index' (duration: 14.161µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:56:44.935516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.590837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:56:44.935542Z","caller":"traceutil/trace.go:171","msg":"trace[914613980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:915; }","duration":"108.657789ms","start":"2026-02-06T20:56:44.826876Z","end":"2026-02-06T20:56:44.935534Z","steps":["trace[914613980] 'agreement among raft nodes before linearized reading' (duration: 108.594608ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:56:47.203958Z","caller":"traceutil/trace.go:171","msg":"trace[434346695] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"240.908541ms","start":"2026-02-06T20:56:46.963028Z","end":"2026-02-06T20:56:47.203937Z","steps":["trace[434346695] 'process raft request' (duration: 224.018449ms)","trace[434346695] 'compare' (duration: 16.495142ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:56:47.611656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.119235ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T20:56:47.611758Z","caller":"traceutil/trace.go:171","msg":"trace[1280769590] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:957; }","duration":"327.207128ms","start":"2026-02-06T20:56:47.28454Z","end":"2026-02-06T20:56:47.611747Z","steps":["trace[1280769590] 'read index received' (duration: 123.891138ms)","trace[1280769590] 'applied index is now lower than readState.Index' (duration: 203.31484ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:56:47.611903Z","caller":"traceutil/trace.go:171","msg":"trace[987968676] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"330.907032ms","start":"2026-02-06T20:56:47.280988Z","end":"2026-02-06T20:56:47.611895Z","steps":["trace[987968676] 'process raft request' (duration: 127.48387ms)","trace[987968676] 'compare' (duration: 202.960961ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:56:47.611944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:47.28098Z","time spent":"330.941283ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:56:47.612101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.558016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-06T20:56:47.612119Z","caller":"traceutil/trace.go:171","msg":"trace[1497798288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:923; }","duration":"327.584477ms","start":"2026-02-06T20:56:47.284529Z","end":"2026-02-06T20:56:47.612113Z","steps":["trace[1497798288] 'agreement among raft nodes before linearized reading' (duration: 327.511465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:47.612136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:47.284524Z","time spent":"327.608397ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-06T20:56:47.612272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.236208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-06T20:56:47.61229Z","caller":"traceutil/trace.go:171","msg":"trace[1477295657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:923; }","duration":"327.26237ms","start":"2026-02-06T20:56:47.285022Z","end":"2026-02-06T20:56:47.612284Z","steps":["trace[1477295657] 'agreement among raft nodes before linearized reading' (duration: 327.211518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:47.612327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:47.285015Z","time spent":"327.306621ms","remote":"127.0.0.1:33470","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":"info","ts":"2026-02-06T20:56:47.852309Z","caller":"traceutil/trace.go:171","msg":"trace[1561352741] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"232.610918ms","start":"2026-02-06T20:56:47.619682Z","end":"2026-02-06T20:56:47.852293Z","steps":["trace[1561352741] 'process raft request' (duration: 138.494832ms)","trace[1561352741] 'compare' (duration: 93.85077ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:56:49.25961Z","caller":"traceutil/trace.go:171","msg":"trace[400232019] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"313.668751ms","start":"2026-02-06T20:56:48.945928Z","end":"2026-02-06T20:56:49.259597Z","steps":["trace[400232019] 'process raft request' (duration: 313.572519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:49.259727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:48.945912Z","time spent":"313.748234ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:56:50.22954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.960877ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-06T20:56:50.229687Z","caller":"traceutil/trace.go:171","msg":"trace[839136493] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"996.987385ms","start":"2026-02-06T20:56:49.232686Z","end":"2026-02-06T20:56:50.229673Z","steps":["trace[839136493] 'read index received' (duration: 27.343549ms)","trace[839136493] 'applied index is now lower than readState.Index' (duration: 969.643136ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:56:50.229928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"997.234151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-06T20:56:50.229948Z","caller":"traceutil/trace.go:171","msg":"trace[862188785] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:926; }","duration":"997.291893ms","start":"2026-02-06T20:56:49.23265Z","end":"2026-02-06T20:56:50.229942Z","steps":["trace[862188785] 'agreement among raft nodes before linearized reading' (duration: 997.202621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:50.229969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:49.23263Z","time spent":"997.333684ms","remote":"127.0.0.1:33470","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":"info","ts":"2026-02-06T20:56:50.230214Z","caller":"traceutil/trace.go:171","msg":"trace[859487861] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"903.253717ms","start":"2026-02-06T20:56:49.326953Z","end":"2026-02-06T20:56:50.230207Z","steps":["trace[859487861] 'process raft request' (duration: 902.658392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:50.230271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:49.326936Z","time spent":"903.296278ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:56:50.254943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.67274ms","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-02-06T20:56:50.254998Z","caller":"traceutil/trace.go:171","msg":"trace[1669168713] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:927; }","duration":"636.784982ms","start":"2026-02-06T20:56:49.6182Z","end":"2026-02-06T20:56:50.254985Z","steps":["trace[1669168713] 'agreement among raft nodes before linearized reading' (duration: 611.997948ms)","trace[1669168713] 'range keys from in-memory index tree' (duration: 24.665421ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:56:50.25503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:49.618178Z","time spent":"636.845454ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-06T20:56:50.255257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.198986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-06T20:56:50.255277Z","caller":"traceutil/trace.go:171","msg":"trace[1601101022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:927; }","duration":"397.254638ms","start":"2026-02-06T20:56:49.858016Z","end":"2026-02-06T20:56:50.25527Z","steps":["trace[1601101022] 'agreement among raft nodes before linearized reading' (duration: 372.34254ms)","trace[1601101022] 'range keys from in-memory index tree' (duration: 24.864957ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:56:50.255293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:49.858Z","time spent":"397.289789ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-06T20:56:50.255365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.926202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:56:50.255382Z","caller":"traceutil/trace.go:171","msg":"trace[1739061099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:927; }","duration":"427.971173ms","start":"2026-02-06T20:56:49.827404Z","end":"2026-02-06T20:56:50.255375Z","steps":["trace[1739061099] 'agreement among raft nodes before linearized reading' (duration: 402.960383ms)","trace[1739061099] 'range keys from in-memory index tree' (duration: 24.990969ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:56:50.255399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:49.827371Z","time spent":"428.023385ms","remote":"127.0.0.1:33212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T20:56:50.255506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.699431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-06T20:56:50.255534Z","caller":"traceutil/trace.go:171","msg":"trace[1155606718] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:927; }","duration":"586.771934ms","start":"2026-02-06T20:56:49.668757Z","end":"2026-02-06T20:56:50.255529Z","steps":["trace[1155606718] 'agreement among raft nodes before linearized reading' (duration: 561.613011ms)","trace[1155606718] 'range keys from in-memory index tree' (duration: 25.101192ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:56:50.255554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:49.668735Z","time spent":"586.814315ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-06T20:56:51.361798Z","caller":"traceutil/trace.go:171","msg":"trace[637787990] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:967; }","duration":"250.947888ms","start":"2026-02-06T20:56:51.110832Z","end":"2026-02-06T20:56:51.36178Z","steps":["trace[637787990] 'read index received' (duration: 250.795354ms)","trace[637787990] 'applied index is now lower than readState.Index' (duration: 151.494µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:56:51.361875Z","caller":"traceutil/trace.go:171","msg":"trace[1409769309] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"287.720478ms","start":"2026-02-06T20:56:51.074148Z","end":"2026-02-06T20:56:51.361869Z","steps":["trace[1409769309] 'process raft request' (duration: 287.530793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:51.362153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.302587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T20:56:51.362175Z","caller":"traceutil/trace.go:171","msg":"trace[506403175] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:932; }","duration":"251.370619ms","start":"2026-02-06T20:56:51.110798Z","end":"2026-02-06T20:56:51.362169Z","steps":["trace[506403175] 'agreement among raft nodes before linearized reading' (duration: 251.248355ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:56:51.880673Z","caller":"traceutil/trace.go:171","msg":"trace[985569242] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"514.747245ms","start":"2026-02-06T20:56:51.365908Z","end":"2026-02-06T20:56:51.880656Z","steps":["trace[985569242] 'process raft request' (duration: 494.002685ms)","trace[985569242] 'compare' (duration: 20.379581ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:56:51.880766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:51.365892Z","time spent":"514.823117ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:56:57.774791Z","caller":"traceutil/trace.go:171","msg":"trace[1288835829] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1011; }","duration":"423.756947ms","start":"2026-02-06T20:56:57.351018Z","end":"2026-02-06T20:56:57.774775Z","steps":["trace[1288835829] 'read index received' (duration: 423.687445ms)","trace[1288835829] 'applied index is now lower than readState.Index' (duration: 68.872µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:56:57.774886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.847019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:56:57.774902Z","caller":"traceutil/trace.go:171","msg":"trace[723710805] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:975; }","duration":"423.910781ms","start":"2026-02-06T20:56:57.350987Z","end":"2026-02-06T20:56:57.774898Z","steps":["trace[723710805] 'agreement among raft nodes before linearized reading' (duration: 423.85473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:57.774922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:57.350972Z","time spent":"423.944872ms","remote":"127.0.0.1:33328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-02-06T20:56:57.775147Z","caller":"traceutil/trace.go:171","msg":"trace[497171955] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"563.44272ms","start":"2026-02-06T20:56:57.211679Z","end":"2026-02-06T20:56:57.775122Z","steps":["trace[497171955] 'process raft request' (duration: 562.997508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:56:57.775437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:56:57.211665Z","time spent":"563.620595ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:57:00.501726Z","caller":"traceutil/trace.go:171","msg":"trace[1475687411] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1027; }","duration":"172.700057ms","start":"2026-02-06T20:57:00.329006Z","end":"2026-02-06T20:57:00.501706Z","steps":["trace[1475687411] 'read index received' (duration: 172.509302ms)","trace[1475687411] 'applied index is now lower than readState.Index' (duration: 189.925µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:57:00.502294Z","caller":"traceutil/trace.go:171","msg":"trace[997081444] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"200.253012ms","start":"2026-02-06T20:57:00.302024Z","end":"2026-02-06T20:57:00.502277Z","steps":["trace[997081444] 'process raft request' (duration: 199.545654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:57:00.504961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.93746ms","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-02-06T20:57:00.505244Z","caller":"traceutil/trace.go:171","msg":"trace[261027437] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:990; }","duration":"176.259188ms","start":"2026-02-06T20:57:00.32897Z","end":"2026-02-06T20:57:00.505229Z","steps":["trace[261027437] 'agreement among raft nodes before linearized reading' (duration: 175.655412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:57:00.505972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.109888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-06T20:57:00.506842Z","caller":"traceutil/trace.go:171","msg":"trace[687279488] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:990; }","duration":"175.966989ms","start":"2026-02-06T20:57:00.330795Z","end":"2026-02-06T20:57:00.506762Z","steps":["trace[687279488] 'agreement among raft nodes before linearized reading' (duration: 174.874602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:57:00.511163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.427711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-06T20:57:00.513914Z","caller":"traceutil/trace.go:171","msg":"trace[163968715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:990; }","duration":"150.198111ms","start":"2026-02-06T20:57:00.363705Z","end":"2026-02-06T20:57:00.513903Z","steps":["trace[163968715] 'agreement among raft nodes before linearized reading' (duration: 147.40909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:57:00.510892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.071845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-06T20:57:00.514211Z","caller":"traceutil/trace.go:171","msg":"trace[898795536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:990; }","duration":"149.419871ms","start":"2026-02-06T20:57:00.364783Z","end":"2026-02-06T20:57:00.514203Z","steps":["trace[898795536] 'agreement among raft nodes before linearized reading' (duration: 146.037375ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:57:08.079032Z","caller":"traceutil/trace.go:171","msg":"trace[2106857604] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"119.922006ms","start":"2026-02-06T20:57:07.959084Z","end":"2026-02-06T20:57:08.079006Z","steps":["trace[2106857604] 'process raft request' (duration: 119.413643ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:57:08.392749Z","caller":"traceutil/trace.go:171","msg":"trace[332337492] linearizableReadLoop","detail":"{readStateIndex:1082; appliedIndex:1081; }","duration":"220.222371ms","start":"2026-02-06T20:57:08.172504Z","end":"2026-02-06T20:57:08.392727Z","steps":["trace[332337492] 'read index received' (duration: 220.032786ms)","trace[332337492] 'applied index is now lower than readState.Index' (duration: 188.575µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:57:08.393257Z","caller":"traceutil/trace.go:171","msg":"trace[1344037724] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"307.463433ms","start":"2026-02-06T20:57:08.085776Z","end":"2026-02-06T20:57:08.393239Z","steps":["trace[1344037724] 'process raft request' (duration: 306.822146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:57:08.394949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:57:08.085756Z","time spent":"309.122256ms","remote":"127.0.0.1:33470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:57:08.393287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.771416ms","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:531"} {"level":"info","ts":"2026-02-06T20:57:08.395263Z","caller":"traceutil/trace.go:171","msg":"trace[790755267] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1043; }","duration":"222.801597ms","start":"2026-02-06T20:57:08.172447Z","end":"2026-02-06T20:57:08.395249Z","steps":["trace[790755267] 'agreement among raft nodes before linearized reading' (duration: 220.366784ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:59:35.546073Z","caller":"traceutil/trace.go:171","msg":"trace[657637183] transaction","detail":"{read_only:false; response_revision:1649; number_of_response:1; }","duration":"113.089516ms","start":"2026-02-06T20:59:35.432949Z","end":"2026-02-06T20:59:35.546038Z","steps":["trace[657637183] 'process raft request' (duration: 105.656383ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:59:49.217379Z","caller":"traceutil/trace.go:171","msg":"trace[1299230999] transaction","detail":"{read_only:false; response_revision:1699; number_of_response:1; }","duration":"179.633094ms","start":"2026-02-06T20:59:49.037684Z","end":"2026-02-06T20:59:49.217318Z","steps":["trace[1299230999] 'process raft request' (duration: 179.401777ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:59:49.472335Z","caller":"traceutil/trace.go:171","msg":"trace[242967228] transaction","detail":"{read_only:false; response_revision:1700; number_of_response:1; }","duration":"248.726671ms","start":"2026-02-06T20:59:49.22358Z","end":"2026-02-06T20:59:49.472306Z","steps":["trace[242967228] 'process raft request' (duration: 228.428039ms)","trace[242967228] 'compare' (duration: 19.228825ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:59:49.489414Z","caller":"traceutil/trace.go:171","msg":"trace[2121422018] transaction","detail":"{read_only:false; response_revision:1701; number_of_response:1; }","duration":"262.4185ms","start":"2026-02-06T20:59:49.226959Z","end":"2026-02-06T20:59:49.489378Z","steps":["trace[2121422018] 'process raft request' (duration: 262.01586ms)"],"step_count":1}