{"level":"warn","ts":"2026-06-28T19:50:03.569713Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-28T19:50:03.569789Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.229:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.229:2380","--initial-cluster=instance=https://199.204.45.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.229: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-06-28T19:50:03.569851Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-28T19:50:03.569862Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-06-28T19:50:03.569877Z","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-06-28T19:50:03.570512Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"]} {"level":"info","ts":"2026-06-28T19:50:03.570585Z","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":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.229: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-06-28T19:50:03.574681Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.891541ms"} {"level":"info","ts":"2026-06-28T19:50:03.582592Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ba9ae53cc4335db","cluster-id":"4ad2674607ff1d60"} {"level":"info","ts":"2026-06-28T19:50:03.582696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=()"} {"level":"info","ts":"2026-06-28T19:50:03.582817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 0"} {"level":"info","ts":"2026-06-28T19:50:03.582892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3ba9ae53cc4335db [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-28T19:50:03.582908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 1"} {"level":"info","ts":"2026-06-28T19:50:03.583083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"warn","ts":"2026-06-28T19:50:03.59667Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-28T19:50:03.599683Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-28T19:50:03.602238Z","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-06-28T19:50:03.606184Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3ba9ae53cc4335db","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-28T19:50:03.607714Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3ba9ae53cc4335db","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-28T19:50:03.607801Z","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-06-28T19:50:03.607929Z","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-06-28T19:50:03.607937Z","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-06-28T19:50:03.608281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"info","ts":"2026-06-28T19:50:03.608421Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","added-peer-id":"3ba9ae53cc4335db","added-peer-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-06-28T19:50:03.610545Z","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-06-28T19:50:03.610944Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3ba9ae53cc4335db","initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-28T19:50:03.610991Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-28T19:50:03.611167Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-06-28T19:50:03.611189Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-06-28T19:50:04.183617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db is starting a new election at term 1"} {"level":"info","ts":"2026-06-28T19:50:04.183664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became pre-candidate at term 1"} {"level":"info","ts":"2026-06-28T19:50:04.183689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgPreVoteResp from 3ba9ae53cc4335db at term 1"} {"level":"info","ts":"2026-06-28T19:50:04.183699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became candidate at term 2"} {"level":"info","ts":"2026-06-28T19:50:04.183706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgVoteResp from 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-06-28T19:50:04.183718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became leader at term 2"} {"level":"info","ts":"2026-06-28T19:50:04.183725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ba9ae53cc4335db elected leader 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-06-28T19:50:04.191577Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3ba9ae53cc4335db","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.229:2379]}","request-path":"/0/members/3ba9ae53cc4335db/attributes","cluster-id":"4ad2674607ff1d60","publish-timeout":"7s"} {"level":"info","ts":"2026-06-28T19:50:04.191589Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-28T19:50:04.191636Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-28T19:50:04.191639Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-28T19:50:04.192814Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","cluster-version":"3.5"} {"level":"info","ts":"2026-06-28T19:50:04.193023Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-28T19:50:04.193145Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-28T19:50:04.195025Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-28T19:50:04.195857Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-28T19:50:04.195898Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-28T19:50:04.204041Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.229:2379"} {"level":"warn","ts":"2026-06-28T19:50:05.952209Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870394041220981,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-28T19:50:05.983168Z","caller":"traceutil/trace.go:171","msg":"trace[1585816042] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"533.671694ms","start":"2026-06-28T19:50:05.449477Z","end":"2026-06-28T19:50:05.983149Z","steps":["trace[1585816042] 'process raft request' (duration: 533.483849ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:05.98366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:05.449468Z","time spent":"533.751536ms","remote":"127.0.0.1:57566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:50:06.646337Z","caller":"traceutil/trace.go:171","msg":"trace[1988140591] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"1.121524746s","start":"2026-06-28T19:50:05.524785Z","end":"2026-06-28T19:50:06.646309Z","steps":["trace[1988140591] 'process raft request' (duration: 1.121255249s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:06.646458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:05.524764Z","time spent":"1.1216479s","remote":"127.0.0.1:57952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-28T19:50:06.646568Z","caller":"traceutil/trace.go:171","msg":"trace[1807834700] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.120999832s","start":"2026-06-28T19:50:05.525561Z","end":"2026-06-28T19:50:06.646561Z","steps":["trace[1807834700] 'process raft request' (duration: 1.120713465s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:06.646606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:05.525544Z","time spent":"1.121040004s","remote":"127.0.0.1:58038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-28T19:50:06.646674Z","caller":"traceutil/trace.go:171","msg":"trace[1069763146] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.121191057s","start":"2026-06-28T19:50:05.525475Z","end":"2026-06-28T19:50:06.646666Z","steps":["trace[1069763146] 'process raft request' (duration: 1.120767636s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:06.646707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:05.525427Z","time spent":"1.12126281s","remote":"127.0.0.1:58038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-28T19:50:06.646679Z","caller":"traceutil/trace.go:171","msg":"trace[1663399334] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:4; }","duration":"1.194999285s","start":"2026-06-28T19:50:05.451633Z","end":"2026-06-28T19:50:06.646632Z","steps":["trace[1663399334] 'read index received' (duration: 531.413365ms)","trace[1663399334] 'applied index is now lower than readState.Index' (duration: 663.58497ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:50:06.646877Z","caller":"traceutil/trace.go:171","msg":"trace[203940003] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.104293468s","start":"2026-06-28T19:50:05.542577Z","end":"2026-06-28T19:50:06.64687Z","steps":["trace[203940003] 'process raft request' (duration: 1.104024221s)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:50:06.64687Z","caller":"traceutil/trace.go:171","msg":"trace[2143866606] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.121157827s","start":"2026-06-28T19:50:05.525681Z","end":"2026-06-28T19:50:06.646839Z","steps":["trace[2143866606] 'process raft request' (duration: 1.120743266s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:06.646918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:05.542562Z","time spent":"1.10434145s","remote":"127.0.0.1:57756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-28T19:50:06.646951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:05.525668Z","time spent":"1.121243689s","remote":"127.0.0.1:58038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-28T19:50:06.646851Z","caller":"traceutil/trace.go:171","msg":"trace[1950014790] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.109942626s","start":"2026-06-28T19:50:05.536896Z","end":"2026-06-28T19:50:06.646839Z","steps":["trace[1950014790] 'process raft request' (duration: 1.109655018s)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:50:06.647028Z","caller":"traceutil/trace.go:171","msg":"trace[1995453876] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.120822798s","start":"2026-06-28T19:50:05.526196Z","end":"2026-06-28T19:50:06.647019Z","steps":["trace[1995453876] 'process raft request' (duration: 1.120255644s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:06.64708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:05.526173Z","time spent":"1.120882689s","remote":"127.0.0.1:58038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-28T19:50:06.647159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.183801584s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-28T19:50:06.64721Z","caller":"traceutil/trace.go:171","msg":"trace[1288366998] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"1.183880246s","start":"2026-06-28T19:50:05.463322Z","end":"2026-06-28T19:50:06.647202Z","steps":["trace[1288366998] 'agreement among raft nodes before linearized reading' (duration: 1.183752193s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:06.64721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.120421928s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-28T19:50:06.647231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:05.463285Z","time spent":"1.183940428s","remote":"127.0.0.1:57898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-06-28T19:50:06.647242Z","caller":"traceutil/trace.go:171","msg":"trace[386228557] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"1.120487279s","start":"2026-06-28T19:50:05.526746Z","end":"2026-06-28T19:50:06.647233Z","steps":["trace[386228557] 'agreement among raft nodes before linearized reading' (duration: 1.120438328s)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:50:06.647198Z","caller":"traceutil/trace.go:171","msg":"trace[1022724261] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.120619373s","start":"2026-06-28T19:50:05.526465Z","end":"2026-06-28T19:50:06.647084Z","steps":["trace[1022724261] 'process raft request' (duration: 1.120039758s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:06.647259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:05.536882Z","time spent":"1.11012019s","remote":"127.0.0.1:57676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-28T19:50:06.647279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:05.526733Z","time spent":"1.120538481s","remote":"127.0.0.1:57582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-06-28T19:50:06.647358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.122630625s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-28T19:50:06.647384Z","caller":"traceutil/trace.go:171","msg":"trace[1037813092] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.122691177s","start":"2026-06-28T19:50:05.524687Z","end":"2026-06-28T19:50:06.647378Z","steps":["trace[1037813092] 'agreement among raft nodes before linearized reading' (duration: 1.122653686s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:06.647422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:05.524669Z","time spent":"1.122747918s","remote":"127.0.0.1:57606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-28T19:50:06.64738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:05.526456Z","time spent":"1.120831808s","remote":"127.0.0.1:58038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-28T19:50:06.647452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.838084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-28T19:50:06.64751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124328739s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-28T19:50:06.647534Z","caller":"traceutil/trace.go:171","msg":"trace[1319595997] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:0; response_revision:10; }","duration":"1.124392591s","start":"2026-06-28T19:50:05.523136Z","end":"2026-06-28T19:50:06.647529Z","steps":["trace[1319595997] 'agreement among raft nodes before linearized reading' (duration: 1.12436062s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:06.64755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:05.523116Z","time spent":"1.124429782s","remote":"127.0.0.1:57546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":26,"request content":"key:\"/registry/masterleases/199.204.45.229\" "} {"level":"info","ts":"2026-06-28T19:50:06.647549Z","caller":"traceutil/trace.go:171","msg":"trace[116939693] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:10; }","duration":"224.897506ms","start":"2026-06-28T19:50:06.422575Z","end":"2026-06-28T19:50:06.647472Z","steps":["trace[116939693] 'agreement among raft nodes before linearized reading' (duration: 224.852095ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:06.647647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145548171s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-28T19:50:06.647672Z","caller":"traceutil/trace.go:171","msg":"trace[1654379933] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.145572491s","start":"2026-06-28T19:50:05.502094Z","end":"2026-06-28T19:50:06.647667Z","steps":["trace[1654379933] 'agreement among raft nodes before linearized reading' (duration: 1.14553794s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:06.647712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:05.502072Z","time spent":"1.145613213s","remote":"127.0.0.1:57606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-28T19:50:06.647683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070310015s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-28T19:50:06.649543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.197331145s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-28T19:50:06.651115Z","caller":"traceutil/trace.go:171","msg":"trace[14049092] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:10; }","duration":"1.199503802s","start":"2026-06-28T19:50:05.451583Z","end":"2026-06-28T19:50:06.651087Z","steps":["trace[14049092] 'agreement among raft nodes before linearized reading' (duration: 1.195387245s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:06.651549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:05.451564Z","time spent":"1.199933483s","remote":"127.0.0.1:57692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":26,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"warn","ts":"2026-06-28T19:50:06.647792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.185154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-28T19:50:06.65177Z","caller":"traceutil/trace.go:171","msg":"trace[629163039] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:10; }","duration":"229.173297ms","start":"2026-06-28T19:50:06.422545Z","end":"2026-06-28T19:50:06.651719Z","steps":["trace[629163039] 'agreement among raft nodes before linearized reading' (duration: 225.146703ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:50:06.647767Z","caller":"traceutil/trace.go:171","msg":"trace[1724205912] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.070451549s","start":"2026-06-28T19:50:05.5773Z","end":"2026-06-28T19:50:06.647752Z","steps":["trace[1724205912] 'agreement among raft nodes before linearized reading' (duration: 1.070308346s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:06.651962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:05.577284Z","time spent":"1.074665039s","remote":"127.0.0.1:57606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-06-28T19:50:39.066448Z","caller":"traceutil/trace.go:171","msg":"trace[1168028006] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"120.858004ms","start":"2026-06-28T19:50:38.945558Z","end":"2026-06-28T19:50:39.066416Z","steps":["trace[1168028006] 'process raft request' (duration: 120.667226ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:41.570407Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870394041221957,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-28T19:50:41.994629Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.080471828s","expected-duration":"1s"} {"level":"info","ts":"2026-06-28T19:50:41.995092Z","caller":"traceutil/trace.go:171","msg":"trace[893486914] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"1.080988167s","start":"2026-06-28T19:50:40.914071Z","end":"2026-06-28T19:50:41.99506Z","steps":["trace[893486914] 'process raft request' (duration: 1.080840431s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:41.995327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:40.914052Z","time spent":"1.081127882s","remote":"127.0.0.1:57554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-28T19:50:42.038062Z","caller":"traceutil/trace.go:171","msg":"trace[228171678] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"1.035797082s","start":"2026-06-28T19:50:41.00224Z","end":"2026-06-28T19:50:42.038037Z","steps":["trace[228171678] 'process raft request' (duration: 1.035533833s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:42.038183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:41.002223Z","time spent":"1.035903636s","remote":"127.0.0.1:57554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-28T19:50:42.038332Z","caller":"traceutil/trace.go:171","msg":"trace[1391661931] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"1.035894315s","start":"2026-06-28T19:50:41.002417Z","end":"2026-06-28T19:50:42.038311Z","steps":["trace[1391661931] 'process raft request' (duration: 1.03547775s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:42.038456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:41.00241Z","time spent":"1.036002249s","remote":"127.0.0.1:57554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-28T19:50:42.038864Z","caller":"traceutil/trace.go:171","msg":"trace[314773492] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"1.026400698s","start":"2026-06-28T19:50:41.012442Z","end":"2026-06-28T19:50:42.038842Z","steps":["trace[314773492] 'process raft request' (duration: 1.025720203s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:42.039033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:41.012435Z","time spent":"1.026491411s","remote":"127.0.0.1:57554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-28T19:50:42.038982Z","caller":"traceutil/trace.go:171","msg":"trace[1237775793] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"1.025705082s","start":"2026-06-28T19:50:41.013231Z","end":"2026-06-28T19:50:42.038937Z","steps":["trace[1237775793] 'process raft request' (duration: 1.025456083s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:42.039306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:41.013225Z","time spent":"1.025912539s","remote":"127.0.0.1:57554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-28T19:50:42.039566Z","caller":"traceutil/trace.go:171","msg":"trace[1987059115] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"1.024064871s","start":"2026-06-28T19:50:41.015483Z","end":"2026-06-28T19:50:42.039548Z","steps":["trace[1987059115] 'process raft request' (duration: 1.023808072s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:42.039649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:41.015478Z","time spent":"1.024134204s","remote":"127.0.0.1:57554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-28T19:50:42.039758Z","caller":"traceutil/trace.go:171","msg":"trace[1213183096] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"1.023330245s","start":"2026-06-28T19:50:41.016414Z","end":"2026-06-28T19:50:42.039744Z","steps":["trace[1213183096] 'process raft request' (duration: 1.022956081s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:42.039807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:41.016408Z","time spent":"1.023377696s","remote":"127.0.0.1:57554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-28T19:50:42.039898Z","caller":"traceutil/trace.go:171","msg":"trace[438577060] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"1.022134271s","start":"2026-06-28T19:50:41.017753Z","end":"2026-06-28T19:50:42.039888Z","steps":["trace[438577060] 'process raft request' (duration: 1.02182942s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:42.039945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:41.017748Z","time spent":"1.022176762s","remote":"127.0.0.1:57554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-28T19:50:42.040099Z","caller":"traceutil/trace.go:171","msg":"trace[742973750] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"1.01391727s","start":"2026-06-28T19:50:41.026165Z","end":"2026-06-28T19:50:42.040083Z","steps":["trace[742973750] 'process raft request' (duration: 1.013593388s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:42.040251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:41.02616Z","time spent":"1.013993732s","remote":"127.0.0.1:57554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-28T19:50:42.04126Z","caller":"traceutil/trace.go:171","msg":"trace[1028331981] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"1.013997452s","start":"2026-06-28T19:50:41.027224Z","end":"2026-06-28T19:50:42.041222Z","steps":["trace[1028331981] 'process raft request' (duration: 1.012810389s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:42.041349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:41.027218Z","time spent":"1.014090016s","remote":"127.0.0.1:57554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-28T19:50:42.041508Z","caller":"traceutil/trace.go:171","msg":"trace[1804701306] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:504; }","duration":"971.721305ms","start":"2026-06-28T19:50:41.069753Z","end":"2026-06-28T19:50:42.041475Z","steps":["trace[1804701306] 'read index received' (duration: 925.253489ms)","trace[1804701306] 'applied index is now lower than readState.Index' (duration: 46.466576ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:50:42.041612Z","caller":"traceutil/trace.go:171","msg":"trace[214175275] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"897.770185ms","start":"2026-06-28T19:50:41.143833Z","end":"2026-06-28T19:50:42.041603Z","steps":["trace[214175275] 'process raft request' (duration: 897.446444ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:42.041669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:41.143812Z","time spent":"897.832038ms","remote":"127.0.0.1:57554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-28T19:50:42.041565Z","caller":"traceutil/trace.go:171","msg":"trace[428537859] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"1.001658861s","start":"2026-06-28T19:50:41.039895Z","end":"2026-06-28T19:50:42.041553Z","steps":["trace[428537859] 'process raft request' (duration: 1.001222725s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:42.041889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:41.039838Z","time spent":"1.002027574s","remote":"127.0.0.1:57554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-28T19:50:42.041828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.065707ms","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-06-28T19:50:42.042016Z","caller":"traceutil/trace.go:171","msg":"trace[845677369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:499; }","duration":"972.285995ms","start":"2026-06-28T19:50:41.069712Z","end":"2026-06-28T19:50:42.041998Z","steps":["trace[845677369] 'agreement among raft nodes before linearized reading' (duration: 971.971043ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:42.042115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:41.069696Z","time spent":"972.403379ms","remote":"127.0.0.1:57764","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-06-28T19:50:42.106174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005019554s","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-06-28T19:50:42.106232Z","caller":"traceutil/trace.go:171","msg":"trace[423661433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:501; }","duration":"1.005101827s","start":"2026-06-28T19:50:41.101118Z","end":"2026-06-28T19:50:42.10622Z","steps":["trace[423661433] 'agreement among raft nodes before linearized reading' (duration: 1.005005863s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:42.106257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:41.101096Z","time spent":"1.005155889s","remote":"127.0.0.1:57764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-28T19:50:42.106451Z","caller":"traceutil/trace.go:171","msg":"trace[976199833] transaction","detail":"{read_only:false; number_of_response:1; response_revision:501; }","duration":"102.971125ms","start":"2026-06-28T19:50:42.003449Z","end":"2026-06-28T19:50:42.10642Z","steps":["trace[976199833] 'process raft request' (duration: 102.650994ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:50:42.106532Z","caller":"traceutil/trace.go:171","msg":"trace[1221226616] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"103.613909ms","start":"2026-06-28T19:50:42.002903Z","end":"2026-06-28T19:50:42.106517Z","steps":["trace[1221226616] 'process raft request' (duration: 103.152702ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:50:42.10648Z","caller":"traceutil/trace.go:171","msg":"trace[1358145043] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"105.178366ms","start":"2026-06-28T19:50:42.00129Z","end":"2026-06-28T19:50:42.106469Z","steps":["trace[1358145043] 'process raft request' (duration: 102.781649ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:42.459289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.99256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-28T19:50:42.459418Z","caller":"traceutil/trace.go:171","msg":"trace[1381665607] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"354.681206ms","start":"2026-06-28T19:50:42.104691Z","end":"2026-06-28T19:50:42.459373Z","steps":["trace[1381665607] 'process raft request' (duration: 119.434036ms)","trace[1381665607] 'compare' (duration: 234.839895ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:50:42.45948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:42.104674Z","time spent":"354.769259ms","remote":"127.0.0.1:57554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-28T19:50:42.478445Z","caller":"traceutil/trace.go:171","msg":"trace[1979748765] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"365.991208ms","start":"2026-06-28T19:50:42.112422Z","end":"2026-06-28T19:50:42.478413Z","steps":["trace[1979748765] 'process raft request' (duration: 365.784251ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:42.478568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:42.112401Z","time spent":"366.100713ms","remote":"127.0.0.1:57764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:50:42.478622Z","caller":"traceutil/trace.go:171","msg":"trace[1253787737] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"365.955207ms","start":"2026-06-28T19:50:42.112492Z","end":"2026-06-28T19:50:42.478448Z","steps":["trace[1253787737] 'process raft request' (duration: 365.826122ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:50:42.478672Z","caller":"traceutil/trace.go:171","msg":"trace[400493949] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"364.360809ms","start":"2026-06-28T19:50:42.114285Z","end":"2026-06-28T19:50:42.478646Z","steps":["trace[400493949] 'process raft request' (duration: 364.073958ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:42.478723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:42.112488Z","time spent":"366.207116ms","remote":"127.0.0.1:57764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:50:42.478806Z","caller":"traceutil/trace.go:171","msg":"trace[1240352729] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"354.679465ms","start":"2026-06-28T19:50:42.124118Z","end":"2026-06-28T19:50:42.478797Z","steps":["trace[1240352729] 'process raft request' (duration: 354.290501ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:42.478825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:42.114268Z","time spent":"364.472693ms","remote":"127.0.0.1:57554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T19:50:42.478854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:42.124098Z","time spent":"354.725037ms","remote":"127.0.0.1:57554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:50:42.478872Z","caller":"traceutil/trace.go:171","msg":"trace[610120649] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:528; }","duration":"305.277434ms","start":"2026-06-28T19:50:42.173571Z","end":"2026-06-28T19:50:42.478848Z","steps":["trace[610120649] 'read index received' (duration: 50.672209ms)","trace[610120649] 'applied index is now lower than readState.Index' (duration: 254.604445ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:50:42.478909Z","caller":"traceutil/trace.go:171","msg":"trace[2054858810] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"347.983621ms","start":"2026-06-28T19:50:42.130918Z","end":"2026-06-28T19:50:42.478902Z","steps":["trace[2054858810] 'process raft request' (duration: 347.700871ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:50:42.478958Z","caller":"traceutil/trace.go:171","msg":"trace[1347267062] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"320.50875ms","start":"2026-06-28T19:50:42.158435Z","end":"2026-06-28T19:50:42.478943Z","steps":["trace[1347267062] 'process raft request' (duration: 320.276961ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:50:42.478955Z","caller":"traceutil/trace.go:171","msg":"trace[136917138] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"349.847249ms","start":"2026-06-28T19:50:42.129101Z","end":"2026-06-28T19:50:42.478949Z","steps":["trace[136917138] 'process raft request' (duration: 349.437714ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:42.479029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.466411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-28T19:50:42.479022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:42.158413Z","time spent":"320.578002ms","remote":"127.0.0.1:57554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:50:42.479052Z","caller":"traceutil/trace.go:171","msg":"trace[1452306442] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:518; }","duration":"305.523593ms","start":"2026-06-28T19:50:42.173522Z","end":"2026-06-28T19:50:42.479045Z","steps":["trace[1452306442] 'agreement among raft nodes before linearized reading' (duration: 305.414079ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:42.479048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:42.13091Z","time spent":"348.060254ms","remote":"127.0.0.1:57554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T19:50:42.479037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:42.129082Z","time spent":"349.931822ms","remote":"127.0.0.1:57554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T19:50:42.479069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:42.173505Z","time spent":"305.560924ms","remote":"127.0.0.1:57764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-28T19:50:42.778377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.076329ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-28T19:50:42.778572Z","caller":"traceutil/trace.go:171","msg":"trace[1525384391] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"312.637332ms","start":"2026-06-28T19:50:42.465907Z","end":"2026-06-28T19:50:42.778544Z","steps":["trace[1525384391] 'process raft request' (duration: 158.252132ms)","trace[1525384391] 'compare' (duration: 153.922223ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:50:42.778682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:42.465891Z","time spent":"312.755036ms","remote":"127.0.0.1:58038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-28T19:50:42.77871Z","caller":"traceutil/trace.go:171","msg":"trace[829067870] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"292.110524ms","start":"2026-06-28T19:50:42.486582Z","end":"2026-06-28T19:50:42.778693Z","steps":["trace[829067870] 'process raft request' (duration: 291.977729ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:50:42.77884Z","caller":"traceutil/trace.go:171","msg":"trace[1962099237] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"292.205297ms","start":"2026-06-28T19:50:42.486496Z","end":"2026-06-28T19:50:42.778701Z","steps":["trace[1962099237] 'process raft request' (duration: 291.981419ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:50:42.778865Z","caller":"traceutil/trace.go:171","msg":"trace[776881555] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:536; }","duration":"299.907178ms","start":"2026-06-28T19:50:42.478932Z","end":"2026-06-28T19:50:42.778839Z","steps":["trace[776881555] 'read index received' (duration: 145.235407ms)","trace[776881555] 'applied index is now lower than readState.Index' (duration: 154.670341ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:50:42.779086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.01812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:50:42.779111Z","caller":"traceutil/trace.go:171","msg":"trace[1708074952] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"212.608463ms","start":"2026-06-28T19:50:42.566486Z","end":"2026-06-28T19:50:42.779095Z","steps":["trace[1708074952] 'process raft request' (duration: 212.51893ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:50:42.779147Z","caller":"traceutil/trace.go:171","msg":"trace[1761324443] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"290.907599ms","start":"2026-06-28T19:50:42.488213Z","end":"2026-06-28T19:50:42.77912Z","steps":["trace[1761324443] 'process raft request' (duration: 290.417201ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:50:42.779131Z","caller":"traceutil/trace.go:171","msg":"trace[399386052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:522; }","duration":"584.097762ms","start":"2026-06-28T19:50:42.195022Z","end":"2026-06-28T19:50:42.77912Z","steps":["trace[399386052] 'agreement among raft nodes before linearized reading' (duration: 583.869484ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:42.781437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:42.195004Z","time spent":"586.411307ms","remote":"127.0.0.1:57490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-28T19:50:42.95882Z","caller":"traceutil/trace.go:171","msg":"trace[166896735] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"127.350514ms","start":"2026-06-28T19:50:42.831444Z","end":"2026-06-28T19:50:42.958794Z","steps":["trace[166896735] 'process raft request' (duration: 127.217369ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:50:42.965159Z","caller":"traceutil/trace.go:171","msg":"trace[1525585151] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"128.348071ms","start":"2026-06-28T19:50:42.836795Z","end":"2026-06-28T19:50:42.965143Z","steps":["trace[1525585151] 'process raft request' (duration: 128.194445ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:50:42.965368Z","caller":"traceutil/trace.go:171","msg":"trace[1497972552] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"129.378059ms","start":"2026-06-28T19:50:42.835981Z","end":"2026-06-28T19:50:42.965359Z","steps":["trace[1497972552] 'process raft request' (duration: 128.893411ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:50:48.252017Z","caller":"traceutil/trace.go:171","msg":"trace[1865119600] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"118.84138ms","start":"2026-06-28T19:50:48.133118Z","end":"2026-06-28T19:50:48.251959Z","steps":["trace[1865119600] 'read index received' (duration: 107.860669ms)","trace[1865119600] 'applied index is now lower than readState.Index' (duration: 10.978981ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:50:48.252459Z","caller":"traceutil/trace.go:171","msg":"trace[2064606731] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"190.142258ms","start":"2026-06-28T19:50:48.062268Z","end":"2026-06-28T19:50:48.25241Z","steps":["trace[2064606731] 'process raft request' (duration: 178.987341ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:48.253297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.157067ms","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-06-28T19:50:48.253374Z","caller":"traceutil/trace.go:171","msg":"trace[2041991618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:592; }","duration":"120.286902ms","start":"2026-06-28T19:50:48.133069Z","end":"2026-06-28T19:50:48.253356Z","steps":["trace[2041991618] 'agreement among raft nodes before linearized reading' (duration: 119.304087ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:49.015927Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870394041222129,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-28T19:50:49.430963Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.112891171s","expected-duration":"1s"} {"level":"info","ts":"2026-06-28T19:50:49.431534Z","caller":"traceutil/trace.go:171","msg":"trace[1943146773] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:613; }","duration":"916.830672ms","start":"2026-06-28T19:50:48.514674Z","end":"2026-06-28T19:50:49.431505Z","steps":["trace[1943146773] 'read index received' (duration: 916.438678ms)","trace[1943146773] 'applied index is now lower than readState.Index' (duration: 390.884µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:50:49.431923Z","caller":"traceutil/trace.go:171","msg":"trace[455886452] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"1.120110768s","start":"2026-06-28T19:50:48.311769Z","end":"2026-06-28T19:50:49.431879Z","steps":["trace[455886452] 'process raft request' (duration: 1.119675182s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:49.431999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.31427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-28T19:50:49.432099Z","caller":"traceutil/trace.go:171","msg":"trace[638446722] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"917.417963ms","start":"2026-06-28T19:50:48.514619Z","end":"2026-06-28T19:50:49.432037Z","steps":["trace[638446722] 'agreement among raft nodes before linearized reading' (duration: 917.247587ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:49.432159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:48.514593Z","time spent":"917.552978ms","remote":"127.0.0.1:57764","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-06-28T19:50:49.432159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:48.311751Z","time spent":"1.120266393s","remote":"127.0.0.1:57864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-28T19:50:49.432352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.616029ms","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-06-28T19:50:49.432434Z","caller":"traceutil/trace.go:171","msg":"trace[1622579890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"865.738383ms","start":"2026-06-28T19:50:48.566674Z","end":"2026-06-28T19:50:49.432412Z","steps":["trace[1622579890] 'agreement among raft nodes before linearized reading' (duration: 865.502355ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:49.432506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:48.566655Z","time spent":"865.838487ms","remote":"127.0.0.1:57764","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-06-28T19:50:49.43263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.811313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:50:49.432678Z","caller":"traceutil/trace.go:171","msg":"trace[519129280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"230.910336ms","start":"2026-06-28T19:50:49.201754Z","end":"2026-06-28T19:50:49.432664Z","steps":["trace[519129280] 'agreement among raft nodes before linearized reading' (duration: 230.833444ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:49.432694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.929839ms","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-06-28T19:50:49.432743Z","caller":"traceutil/trace.go:171","msg":"trace[996041125] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"635.027393ms","start":"2026-06-28T19:50:48.797702Z","end":"2026-06-28T19:50:49.43273Z","steps":["trace[996041125] 'agreement among raft nodes before linearized reading' (duration: 634.901398ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:49.43278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:48.797666Z","time spent":"635.103945ms","remote":"127.0.0.1:57764","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-06-28T19:50:49.433056Z","caller":"traceutil/trace.go:171","msg":"trace[53586503] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"1.161054424s","start":"2026-06-28T19:50:48.27198Z","end":"2026-06-28T19:50:49.433034Z","steps":["trace[53586503] 'process raft request' (duration: 1.159218109s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:49.433167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:48.271959Z","time spent":"1.161150018s","remote":"127.0.0.1:57688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T19:50:50.696699Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870394041222139,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-28T19:50:51.197895Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870394041222139,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-28T19:50:51.323446Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.434543126s","expected-duration":"1s"} {"level":"info","ts":"2026-06-28T19:50:51.324608Z","caller":"traceutil/trace.go:171","msg":"trace[1770049909] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"1.128780811s","start":"2026-06-28T19:50:50.195798Z","end":"2026-06-28T19:50:51.324579Z","steps":["trace[1770049909] 'read index received' (duration: 1.127800427s)","trace[1770049909] 'applied index is now lower than readState.Index' (duration: 979.004µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:50:51.32466Z","caller":"traceutil/trace.go:171","msg":"trace[161623632] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"1.437341935s","start":"2026-06-28T19:50:49.887289Z","end":"2026-06-28T19:50:51.324631Z","steps":["trace[161623632] 'process raft request' (duration: 1.43661066s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:51.324798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:49.887257Z","time spent":"1.437450139s","remote":"127.0.0.1:57588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T19:50:51.325064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002003413s","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-06-28T19:50:51.325081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.129217987s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:50:51.325373Z","caller":"traceutil/trace.go:171","msg":"trace[152421159] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"1.12959286s","start":"2026-06-28T19:50:50.195745Z","end":"2026-06-28T19:50:51.325338Z","steps":["trace[152421159] 'agreement among raft nodes before linearized reading' (duration: 1.129135394s)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:50:51.325394Z","caller":"traceutil/trace.go:171","msg":"trace[1774044207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:600; }","duration":"1.00218164s","start":"2026-06-28T19:50:50.32293Z","end":"2026-06-28T19:50:51.325112Z","steps":["trace[1774044207] 'agreement among raft nodes before linearized reading' (duration: 1.001826518s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:50:51.325484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:50.322908Z","time spent":"1.002558614s","remote":"127.0.0.1:57764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-28T19:50:51.325509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:50:50.195717Z","time spent":"1.129777277s","remote":"127.0.0.1:57490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-28T19:51:07.918683Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870394041222459,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-28T19:51:08.233934Z","caller":"traceutil/trace.go:171","msg":"trace[1249795031] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:787; }","duration":"816.300328ms","start":"2026-06-28T19:51:07.417593Z","end":"2026-06-28T19:51:08.233893Z","steps":["trace[1249795031] 'read index received' (duration: 816.256676ms)","trace[1249795031] 'applied index is now lower than readState.Index' (duration: 42.571µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:51:08.233995Z","caller":"traceutil/trace.go:171","msg":"trace[406690807] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"966.351233ms","start":"2026-06-28T19:51:07.267607Z","end":"2026-06-28T19:51:08.233959Z","steps":["trace[406690807] 'process raft request' (duration: 966.156786ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:51:08.234103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.494114ms","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-06-28T19:51:08.234159Z","caller":"traceutil/trace.go:171","msg":"trace[1556950458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:764; }","duration":"816.609988ms","start":"2026-06-28T19:51:07.417536Z","end":"2026-06-28T19:51:08.234146Z","steps":["trace[1556950458] 'agreement among raft nodes before linearized reading' (duration: 816.454263ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:51:08.234169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:51:07.267582Z","time spent":"966.486987ms","remote":"127.0.0.1:57688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T19:51:08.234192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:51:07.417511Z","time spent":"816.670259ms","remote":"127.0.0.1:57764","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-06-28T19:51:08.239699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.7756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-06-28T19:51:08.239718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.913568ms","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-06-28T19:51:08.239755Z","caller":"traceutil/trace.go:171","msg":"trace[784586297] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:765; }","duration":"773.838092ms","start":"2026-06-28T19:51:07.465902Z","end":"2026-06-28T19:51:08.239741Z","steps":["trace[784586297] 'agreement among raft nodes before linearized reading' (duration: 773.703928ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:51:08.239759Z","caller":"traceutil/trace.go:171","msg":"trace[1755341165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:765; }","duration":"677.973601ms","start":"2026-06-28T19:51:07.561777Z","end":"2026-06-28T19:51:08.23975Z","steps":["trace[1755341165] 'agreement among raft nodes before linearized reading' (duration: 677.880218ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:51:08.239789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:51:07.465844Z","time spent":"773.934356ms","remote":"127.0.0.1:57546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.229\" "} {"level":"warn","ts":"2026-06-28T19:51:08.239877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.429969ms","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-06-28T19:51:08.239758Z","caller":"traceutil/trace.go:171","msg":"trace[394273263] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"112.441013ms","start":"2026-06-28T19:51:08.127305Z","end":"2026-06-28T19:51:08.239746Z","steps":["trace[394273263] 'process raft request' (duration: 112.220365ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:51:08.239915Z","caller":"traceutil/trace.go:171","msg":"trace[202625785] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:765; }","duration":"679.503051ms","start":"2026-06-28T19:51:07.560404Z","end":"2026-06-28T19:51:08.239907Z","steps":["trace[202625785] 'agreement among raft nodes before linearized reading' (duration: 679.401558ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:51:08.239949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:51:07.560387Z","time spent":"679.554723ms","remote":"127.0.0.1:57764","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-06-28T19:51:08.239789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:51:07.561767Z","time spent":"678.014102ms","remote":"127.0.0.1:57764","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-06-28T19:51:08.239717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.443816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-28T19:51:08.240064Z","caller":"traceutil/trace.go:171","msg":"trace[2081939804] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:765; }","duration":"672.792168ms","start":"2026-06-28T19:51:07.567261Z","end":"2026-06-28T19:51:08.240053Z","steps":["trace[2081939804] 'agreement among raft nodes before linearized reading' (duration: 672.381874ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:51:08.240104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:51:07.56723Z","time spent":"672.86531ms","remote":"127.0.0.1:57764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-28T19:51:15.165472Z","caller":"traceutil/trace.go:171","msg":"trace[742629596] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"104.84844ms","start":"2026-06-28T19:51:15.060562Z","end":"2026-06-28T19:51:15.165411Z","steps":["trace[742629596] 'process raft request' (duration: 80.268861ms)","trace[742629596] 'compare' (duration: 24.401743ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:51:15.210646Z","caller":"traceutil/trace.go:171","msg":"trace[123311936] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"147.751595ms","start":"2026-06-28T19:51:15.062872Z","end":"2026-06-28T19:51:15.210624Z","steps":["trace[123311936] 'process raft request' (duration: 147.575249ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:51:40.569934Z","caller":"traceutil/trace.go:171","msg":"trace[324216454] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"112.156125ms","start":"2026-06-28T19:51:40.457752Z","end":"2026-06-28T19:51:40.569908Z","steps":["trace[324216454] 'process raft request' (duration: 109.158823ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:51:40.570278Z","caller":"traceutil/trace.go:171","msg":"trace[2107284239] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"109.862914ms","start":"2026-06-28T19:51:40.46035Z","end":"2026-06-28T19:51:40.570213Z","steps":["trace[2107284239] 'process raft request' (duration: 109.518133ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:51:40.570308Z","caller":"traceutil/trace.go:171","msg":"trace[44759744] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"109.116731ms","start":"2026-06-28T19:51:40.461177Z","end":"2026-06-28T19:51:40.570293Z","steps":["trace[44759744] 'process raft request' (duration: 108.894525ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:51:53.371311Z","caller":"traceutil/trace.go:171","msg":"trace[1610820085] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"154.807989ms","start":"2026-06-28T19:51:53.216483Z","end":"2026-06-28T19:51:53.371291Z","steps":["trace[1610820085] 'process raft request' (duration: 154.681075ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:52:06.906237Z","caller":"traceutil/trace.go:171","msg":"trace[1641808754] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"215.503577ms","start":"2026-06-28T19:52:06.690705Z","end":"2026-06-28T19:52:06.906208Z","steps":["trace[1641808754] 'process raft request' (duration: 215.389204ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:52:06.943279Z","caller":"traceutil/trace.go:171","msg":"trace[737278224] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1135; }","duration":"176.079993ms","start":"2026-06-28T19:52:06.767177Z","end":"2026-06-28T19:52:06.943257Z","steps":["trace[737278224] 'read index received' (duration: 138.911963ms)","trace[737278224] 'applied index is now lower than readState.Index' (duration: 37.16707ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:52:06.94343Z","caller":"traceutil/trace.go:171","msg":"trace[821997622] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"252.652877ms","start":"2026-06-28T19:52:06.690724Z","end":"2026-06-28T19:52:06.943377Z","steps":["trace[821997622] 'process raft request' (duration: 252.383559ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:52:06.943554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.311329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:52:06.943601Z","caller":"traceutil/trace.go:171","msg":"trace[825365713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1098; }","duration":"176.454563ms","start":"2026-06-28T19:52:06.767136Z","end":"2026-06-28T19:52:06.943591Z","steps":["trace[825365713] 'agreement among raft nodes before linearized reading' (duration: 176.210076ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:52:09.091976Z","caller":"traceutil/trace.go:171","msg":"trace[528644294] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"140.290728ms","start":"2026-06-28T19:52:08.951644Z","end":"2026-06-28T19:52:09.091934Z","steps":["trace[528644294] 'process raft request' (duration: 92.233935ms)","trace[528644294] 'compare' (duration: 47.929879ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:52:17.64057Z","caller":"traceutil/trace.go:171","msg":"trace[113942452] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"168.096048ms","start":"2026-06-28T19:52:17.472449Z","end":"2026-06-28T19:52:17.640545Z","steps":["trace[113942452] 'process raft request' (duration: 128.443242ms)","trace[113942452] 'compare' (duration: 39.501772ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:52:17.667501Z","caller":"traceutil/trace.go:171","msg":"trace[1171943399] linearizableReadLoop","detail":"{readStateIndex:1175; appliedIndex:1173; }","duration":"131.943874ms","start":"2026-06-28T19:52:17.535541Z","end":"2026-06-28T19:52:17.667485Z","steps":["trace[1171943399] 'read index received' (duration: 65.365479ms)","trace[1171943399] 'applied index is now lower than readState.Index' (duration: 66.577225ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:52:17.667604Z","caller":"traceutil/trace.go:171","msg":"trace[540459182] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"169.232221ms","start":"2026-06-28T19:52:17.498362Z","end":"2026-06-28T19:52:17.667594Z","steps":["trace[540459182] 'process raft request' (duration: 168.14991ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:52:17.667829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.267443ms","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-06-28T19:52:17.667875Z","caller":"traceutil/trace.go:171","msg":"trace[1794168435] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1133; }","duration":"132.366206ms","start":"2026-06-28T19:52:17.535498Z","end":"2026-06-28T19:52:17.667865Z","steps":["trace[1794168435] 'agreement among raft nodes before linearized reading' (duration: 132.209111ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:52:24.838397Z","caller":"traceutil/trace.go:171","msg":"trace[779336023] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"147.52436ms","start":"2026-06-28T19:52:24.690853Z","end":"2026-06-28T19:52:24.838377Z","steps":["trace[779336023] 'process raft request' (duration: 147.327945ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:52:45.242444Z","caller":"traceutil/trace.go:171","msg":"trace[1538959100] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"285.138479ms","start":"2026-06-28T19:52:44.957263Z","end":"2026-06-28T19:52:45.242401Z","steps":["trace[1538959100] 'process raft request' (duration: 284.952964ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:52:45.28089Z","caller":"traceutil/trace.go:171","msg":"trace[947318957] linearizableReadLoop","detail":"{readStateIndex:1286; appliedIndex:1284; }","duration":"157.12378ms","start":"2026-06-28T19:52:45.123751Z","end":"2026-06-28T19:52:45.280875Z","steps":["trace[947318957] 'read index received' (duration: 118.521494ms)","trace[947318957] 'applied index is now lower than readState.Index' (duration: 38.601386ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:52:45.280925Z","caller":"traceutil/trace.go:171","msg":"trace[720037029] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"318.265701ms","start":"2026-06-28T19:52:44.962634Z","end":"2026-06-28T19:52:45.280899Z","steps":["trace[720037029] 'process raft request' (duration: 318.155518ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:52:45.281229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:52:44.962617Z","time spent":"318.520859ms","remote":"127.0.0.1:57580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":853,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-28T19:52:45.281091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.340685ms","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-06-28T19:52:45.281367Z","caller":"traceutil/trace.go:171","msg":"trace[897942593] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1238; }","duration":"157.650524ms","start":"2026-06-28T19:52:45.123691Z","end":"2026-06-28T19:52:45.281342Z","steps":["trace[897942593] 'agreement among raft nodes before linearized reading' (duration: 157.285464ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:52:47.799995Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870394041223649,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-28T19:52:47.965013Z","caller":"traceutil/trace.go:171","msg":"trace[1832203703] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"715.733259ms","start":"2026-06-28T19:52:47.249265Z","end":"2026-06-28T19:52:47.964998Z","steps":["trace[1832203703] 'process raft request' (duration: 715.632816ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:52:47.965126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:52:47.249246Z","time spent":"715.825592ms","remote":"127.0.0.1:57764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:52:47.965088Z","caller":"traceutil/trace.go:171","msg":"trace[1764133966] linearizableReadLoop","detail":"{readStateIndex:1294; appliedIndex:1294; }","duration":"665.201659ms","start":"2026-06-28T19:52:47.299858Z","end":"2026-06-28T19:52:47.96506Z","steps":["trace[1764133966] 'read index received' (duration: 665.190479ms)","trace[1764133966] 'applied index is now lower than readState.Index' (duration: 9.47µs)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:52:47.965376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.491047ms","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-06-28T19:52:47.96549Z","caller":"traceutil/trace.go:171","msg":"trace[888933628] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1246; }","duration":"665.611571ms","start":"2026-06-28T19:52:47.299853Z","end":"2026-06-28T19:52:47.965464Z","steps":["trace[888933628] 'agreement among raft nodes before linearized reading' (duration: 665.351513ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:52:47.965564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:52:47.299811Z","time spent":"665.737484ms","remote":"127.0.0.1:57764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-28T19:52:47.967044Z","caller":"traceutil/trace.go:171","msg":"trace[1478888009] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"121.148812ms","start":"2026-06-28T19:52:47.845865Z","end":"2026-06-28T19:52:47.967014Z","steps":["trace[1478888009] 'process raft request' (duration: 120.912635ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:52:47.967078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.179281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-eafe417a-6a01-4bb2-8d92-f1fd33b41786\" ","response":"range_response_count:1 size:3872"} {"level":"info","ts":"2026-06-28T19:52:47.967138Z","caller":"traceutil/trace.go:171","msg":"trace[2024690265] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-eafe417a-6a01-4bb2-8d92-f1fd33b41786; range_end:; response_count:1; response_revision:1247; }","duration":"226.264354ms","start":"2026-06-28T19:52:47.740855Z","end":"2026-06-28T19:52:47.967119Z","steps":["trace[2024690265] 'agreement among raft nodes before linearized reading' (duration: 226.099979ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:52:47.96721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.007576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-eafe417a-6a01-4bb2-8d92-f1fd33b41786\" ","response":"range_response_count:1 size:3872"} {"level":"info","ts":"2026-06-28T19:52:47.967346Z","caller":"traceutil/trace.go:171","msg":"trace[1047989110] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-eafe417a-6a01-4bb2-8d92-f1fd33b41786; range_end:; response_count:1; response_revision:1247; }","duration":"362.136229ms","start":"2026-06-28T19:52:47.605161Z","end":"2026-06-28T19:52:47.967298Z","steps":["trace[1047989110] 'agreement among raft nodes before linearized reading' (duration: 361.920033ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:52:47.96766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.21526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-06-28T19:52:47.967699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.165465ms","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-06-28T19:52:47.967777Z","caller":"traceutil/trace.go:171","msg":"trace[1353690275] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:1247; }","duration":"498.381494ms","start":"2026-06-28T19:52:47.469362Z","end":"2026-06-28T19:52:47.967743Z","steps":["trace[1353690275] 'agreement among raft nodes before linearized reading' (duration: 498.113337ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:52:47.967829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:52:47.469347Z","time spent":"498.472368ms","remote":"127.0.0.1:57546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.229\" "} {"level":"warn","ts":"2026-06-28T19:52:47.967707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.769198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1490"} {"level":"info","ts":"2026-06-28T19:52:47.967771Z","caller":"traceutil/trace.go:171","msg":"trace[1192415585] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1247; }","duration":"667.876115ms","start":"2026-06-28T19:52:47.299873Z","end":"2026-06-28T19:52:47.967749Z","steps":["trace[1192415585] 'agreement among raft nodes before linearized reading' (duration: 667.054472ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:52:47.967916Z","caller":"traceutil/trace.go:171","msg":"trace[1332266535] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1247; }","duration":"274.008374ms","start":"2026-06-28T19:52:47.6939Z","end":"2026-06-28T19:52:47.967908Z","steps":["trace[1332266535] 'agreement among raft nodes before linearized reading' (duration: 273.742377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:52:47.967935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:52:47.299857Z","time spent":"668.063389ms","remote":"127.0.0.1:57764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-28T19:52:47.967977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:52:47.60515Z","time spent":"362.789788ms","remote":"127.0.0.1:57688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3894,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-eafe417a-6a01-4bb2-8d92-f1fd33b41786\" "} {"level":"info","ts":"2026-06-28T19:52:48.174124Z","caller":"traceutil/trace.go:171","msg":"trace[1506373394] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"203.987015ms","start":"2026-06-28T19:52:47.970113Z","end":"2026-06-28T19:52:48.1741Z","steps":["trace[1506373394] 'process raft request' (duration: 169.545218ms)","trace[1506373394] 'compare' (duration: 34.240301ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:52:48.174463Z","caller":"traceutil/trace.go:171","msg":"trace[881457398] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"202.855672ms","start":"2026-06-28T19:52:47.971589Z","end":"2026-06-28T19:52:48.174444Z","steps":["trace[881457398] 'process raft request' (duration: 202.467351ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:52:48.174499Z","caller":"traceutil/trace.go:171","msg":"trace[1798548732] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"199.602321ms","start":"2026-06-28T19:52:47.974873Z","end":"2026-06-28T19:52:48.174475Z","steps":["trace[1798548732] 'process raft request' (duration: 199.401916ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:52:48.174618Z","caller":"traceutil/trace.go:171","msg":"trace[2147454245] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"171.749789ms","start":"2026-06-28T19:52:48.002855Z","end":"2026-06-28T19:52:48.174605Z","steps":["trace[2147454245] 'process raft request' (duration: 171.629275ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:52:48.17479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.87131ms","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-06-28T19:52:48.174659Z","caller":"traceutil/trace.go:171","msg":"trace[1295528597] linearizableReadLoop","detail":"{readStateIndex:1299; appliedIndex:1296; }","duration":"172.744857ms","start":"2026-06-28T19:52:48.001902Z","end":"2026-06-28T19:52:48.174647Z","steps":["trace[1295528597] 'read index received' (duration: 137.809447ms)","trace[1295528597] 'applied index is now lower than readState.Index' (duration: 34.93425ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:52:48.174823Z","caller":"traceutil/trace.go:171","msg":"trace[666992555] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1251; }","duration":"172.954363ms","start":"2026-06-28T19:52:48.001862Z","end":"2026-06-28T19:52:48.174817Z","steps":["trace[666992555] 'agreement among raft nodes before linearized reading' (duration: 172.821809ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:52:49.298183Z","caller":"traceutil/trace.go:171","msg":"trace[1949275169] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1308; }","duration":"104.351016ms","start":"2026-06-28T19:52:49.19381Z","end":"2026-06-28T19:52:49.298161Z","steps":["trace[1949275169] 'read index received' (duration: 104.229792ms)","trace[1949275169] 'applied index is now lower than readState.Index' (duration: 120.274µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:52:49.298205Z","caller":"traceutil/trace.go:171","msg":"trace[450507178] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"149.377968ms","start":"2026-06-28T19:52:49.148803Z","end":"2026-06-28T19:52:49.29818Z","steps":["trace[450507178] 'process raft request' (duration: 149.247134ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:52:49.298309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.47636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:52:49.298342Z","caller":"traceutil/trace.go:171","msg":"trace[144954957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1259; }","duration":"104.556111ms","start":"2026-06-28T19:52:49.193776Z","end":"2026-06-28T19:52:49.298332Z","steps":["trace[144954957] 'agreement among raft nodes before linearized reading' (duration: 104.470769ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:52:50.113916Z","caller":"traceutil/trace.go:171","msg":"trace[1497469250] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"133.341796ms","start":"2026-06-28T19:52:49.98055Z","end":"2026-06-28T19:52:50.113892Z","steps":["trace[1497469250] 'process raft request' (duration: 116.293488ms)","trace[1497469250] 'compare' (duration: 16.801461ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:53:04.447005Z","caller":"traceutil/trace.go:171","msg":"trace[1720137894] linearizableReadLoop","detail":"{readStateIndex:1375; appliedIndex:1374; }","duration":"173.009793ms","start":"2026-06-28T19:53:04.273971Z","end":"2026-06-28T19:53:04.44698Z","steps":["trace[1720137894] 'read index received' (duration: 161.680999ms)","trace[1720137894] 'applied index is now lower than readState.Index' (duration: 11.327614ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:53:04.44705Z","caller":"traceutil/trace.go:171","msg":"trace[1510436664] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"173.854016ms","start":"2026-06-28T19:53:04.273163Z","end":"2026-06-28T19:53:04.447017Z","steps":["trace[1510436664] 'process raft request' (duration: 162.381618ms)","trace[1510436664] 'compare' (duration: 11.205391ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:53:04.447254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.352703ms","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-06-28T19:53:04.447297Z","caller":"traceutil/trace.go:171","msg":"trace[1444606120] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1322; }","duration":"146.436074ms","start":"2026-06-28T19:53:04.30085Z","end":"2026-06-28T19:53:04.447286Z","steps":["trace[1444606120] 'agreement among raft nodes before linearized reading' (duration: 146.26512ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:04.447418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.313141ms","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-06-28T19:53:04.447466Z","caller":"traceutil/trace.go:171","msg":"trace[1610235197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1322; }","duration":"173.513797ms","start":"2026-06-28T19:53:04.27394Z","end":"2026-06-28T19:53:04.447453Z","steps":["trace[1610235197] 'agreement among raft nodes before linearized reading' (duration: 173.155477ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:53:04.614925Z","caller":"traceutil/trace.go:171","msg":"trace[1365343209] linearizableReadLoop","detail":"{readStateIndex:1377; appliedIndex:1376; }","duration":"158.665875ms","start":"2026-06-28T19:53:04.456238Z","end":"2026-06-28T19:53:04.614904Z","steps":["trace[1365343209] 'read index received' (duration: 1.840131ms)","trace[1365343209] 'applied index is now lower than readState.Index' (duration: 156.824774ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:53:04.615038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.781798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:53:04.615071Z","caller":"traceutil/trace.go:171","msg":"trace[1406468351] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1324; }","duration":"158.84541ms","start":"2026-06-28T19:53:04.456216Z","end":"2026-06-28T19:53:04.615061Z","steps":["trace[1406468351] 'agreement among raft nodes before linearized reading' (duration: 158.766648ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:53:04.615346Z","caller":"traceutil/trace.go:171","msg":"trace[2126112023] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"162.232953ms","start":"2026-06-28T19:53:04.453086Z","end":"2026-06-28T19:53:04.615319Z","steps":["trace[2126112023] 'process raft request' (duration: 130.529993ms)","trace[2126112023] 'compare' (duration: 31.207627ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:53:05.676173Z","caller":"traceutil/trace.go:171","msg":"trace[780359899] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"572.978337ms","start":"2026-06-28T19:53:05.103166Z","end":"2026-06-28T19:53:05.676145Z","steps":["trace[780359899] 'process raft request' (duration: 572.72596ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:05.676279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.857189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:53:05.676323Z","caller":"traceutil/trace.go:171","msg":"trace[1938579435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1325; }","duration":"481.960832ms","start":"2026-06-28T19:53:05.194353Z","end":"2026-06-28T19:53:05.676314Z","steps":["trace[1938579435] 'agreement among raft nodes before linearized reading' (duration: 481.852819ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:05.676347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:05.194332Z","time spent":"482.010073ms","remote":"127.0.0.1:57490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-28T19:53:05.676347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:05.103147Z","time spent":"573.095031ms","remote":"127.0.0.1:57764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:53:05.676144Z","caller":"traceutil/trace.go:171","msg":"trace[225946652] linearizableReadLoop","detail":"{readStateIndex:1378; appliedIndex:1377; }","duration":"481.687674ms","start":"2026-06-28T19:53:05.194402Z","end":"2026-06-28T19:53:05.67609Z","steps":["trace[225946652] 'read index received' (duration: 481.456278ms)","trace[225946652] 'applied index is now lower than readState.Index' (duration: 230.106µs)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:53:05.676785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.586327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:53:05.676816Z","caller":"traceutil/trace.go:171","msg":"trace[726057598] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1325; }","duration":"456.650839ms","start":"2026-06-28T19:53:05.220155Z","end":"2026-06-28T19:53:05.676806Z","steps":["trace[726057598] 'agreement among raft nodes before linearized reading' (duration: 456.592288ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:53:05.676843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:53:05.220138Z","time spent":"456.697981ms","remote":"127.0.0.1:44142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "}